Attachment 703233 Details for Bug 915636 – libvirtd crash log en_US.UTF-8

libvirtd crash log en_US.UTF-8

libvirtd.log (text/plain), 64.42 KB, created by zhpeng on 2013-02-27 02:35:26 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: zhpeng

Created: 2013-02-27 02:35:26 UTC

Size: 64.42 KB

patch

obsolete

>2013-02-27 02:25:12.438+0000: 4752: warning : qemuAgentNotifyEvent:1274 : Received unexpected event 1
>2013-02-27 02:25:12.444+0000: 4752: error : qemuMonitorIO:613 : internal error End of file from monitor
>2013-02-27 02:25:12.444+0000: 4752: error : qemuAgentIO:642 : internal error End of file from monitor
>Caught Segmentation violation dumping internal log buffer:
>
>
>    ====== start of log =====
>
>lInterruptLocked:697 : Skip interrupt, 1 -728332192
>2013-02-27 02:25:11.606+00004752: debug : virEventPollDispatchHandles:469 : i=12 w=13
>2013-02-27 02:25:11.606+00004752: debug : virEventPollCleanupTimeouts:501 : Cleanup 9
>2013-02-27 02:25:11.606+00004752: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2013-02-27 02:25:11.606+00004752: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-02-27 02:25:11.606+00004752: debug : virEventPollCleanupTimeouts:501 : Cleanup 9
>2013-02-27 02:25:11.606+00004752: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=19 e=1 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=24 e=25 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=23 e=25 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=22 e=1 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=27 e=1 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=13, f=30 e=1 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 9 timers
>2013-02-27 02:25:11.606+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931912060
>2013-02-27 02:25:11.606+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931913155
>2013-02-27 02:25:11.606+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931914605
>2013-02-27 02:25:11.606+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931914508
>2013-02-27 02:25:11.606+00004752: debug : virEventPollCalculateTimeout:346 : Timeout at 1361931912060 due in 454 ms
>2013-02-27 02:25:11.606+00004752: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=13 timeout=454
>2013-02-27 02:25:11.606+00004752: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-02-27 02:25:11.606+00004752: debug : virEventPollDispatchTimeouts:410 : Dispatch 9
>2013-02-27 02:25:11.606+00004752: debug : virEventPollDispatchHandles:455 : Dispatch 13
>2013-02-27 02:25:11.606+00004752: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-02-27 02:25:11.606+00004752: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-02-27 02:25:11.606+00004752: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-02-27 02:25:11.606+00004752: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-02-27 02:25:11.606+00004752: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-02-27 02:25:11.606+00004752: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-02-27 02:25:11.606+00004752: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-02-27 02:25:11.606+00004752: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2013-02-27 02:25:11.606+00004752: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2013-02-27 02:25:11.606+00004752: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2013-02-27 02:25:11.606+00004752: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2013-02-27 02:25:11.606+00004752: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2013-02-27 02:25:11.606+00004752: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1
>2013-02-27 02:25:11.606+00004752: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2013-02-27 02:25:11.606+00004752: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x19f3af0 tx=(nil)
>2013-02-27 02:25:11.606+00004752: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-02-27 02:25:11.606+00004752: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-02-27 02:25:11.606+00004752: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -728332192
>2013-02-27 02:25:11.606+00004752: debug : virNetServerClientDispatchRead:875 : RPC_SERVER_CLIENT_MSG_RX: client=0x19f5c20 len=28 prog=1801807216 vers=1 proc=2 type=2 status=0 serial=0
>2013-02-27 02:25:11.606+00004752: debug : virKeepAliveCheckMessage:384 : ka=0x19f48b0, client=0x19f5c20, msg=0x19f3af0
>2013-02-27 02:25:11.606+00004752: debug : virKeepAliveCheckMessage:401 : RPC_KEEPALIVE_RECEIVED: ka=0x19f48b0 client=0x19f5c20 prog=1801807216 vers=1 proc=2
>2013-02-27 02:25:11.606+00004752: debug : virKeepAliveCheckMessage:410 : Got keepalive response from client 0x19f5c20
>2013-02-27 02:25:11.606+00004752: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=6 frequency=3000
>2013-02-27 02:25:11.606+00004752: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -728332192
>2013-02-27 02:25:11.606+00004752: debug : virNetMessageFree:73 : msg=0x19f3af0 nfds=0 cb=(nil)
>2013-02-27 02:25:11.606+00004752: debug : virNetMessageNew:45 : msg=0x19f3af0 tracked=1
>2013-02-27 02:25:11.606+00004752: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x19f3af0 tx=(nil)
>2013-02-27 02:25:11.606+00004752: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-02-27 02:25:11.606+00004752: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-02-27 02:25:11.606+00004752: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -728332192
>2013-02-27 02:25:11.606+00004752: debug : virEventPollDispatchHandles:469 : i=12 w=13
>2013-02-27 02:25:11.606+00004752: debug : virEventPollCleanupTimeouts:501 : Cleanup 9
>2013-02-27 02:25:11.606+00004752: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2013-02-27 02:25:11.606+00004752: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-02-27 02:25:11.606+00004752: debug : virEventPollCleanupTimeouts:501 : Cleanup 9
>2013-02-27 02:25:11.606+00004752: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=19 e=1 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=24 e=25 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=23 e=25 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=22 e=1 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=27 e=1 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=13, f=30 e=1 d=0
>2013-02-27 02:25:11.606+00004752: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 9 timers
>2013-02-27 02:25:11.606+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931912060
>2013-02-27 02:25:11.606+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931913155
>2013-02-27 02:25:11.606+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931914606
>2013-02-27 02:25:11.606+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931914508
>2013-02-27 02:25:11.606+00004752: debug : virEventPollCalculateTimeout:346 : Timeout at 1361931912060 due in 454 ms
>2013-02-27 02:25:11.606+00004752: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=13 timeout=454
>2013-02-27 02:25:12.061+00004752: debug : virEventPollRunOnce:625 : Poll got 0 event(s)
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchTimeouts:410 : Dispatch 9
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchTimeouts:433 : EVENT_POLL_DISPATCH_TIMEOUT: timer=2
>2013-02-27 02:25:12.061+00004752: debug : virKeepAliveTimerInternal:148 : RPC_KEEPALIVE_TIMEOUT: ka=0x19f3db0 client=0x19f33b0 countToDeath=5 idle=3
>2013-02-27 02:25:12.061+00004752: debug : virNetMessageNew:45 : msg=0x19f2bc0 tracked=0
>2013-02-27 02:25:12.061+00004752: debug : virNetMessageEncodePayloadEmpty:448 : Encode length as 28
>2013-02-27 02:25:12.061+00004752: debug : virKeepAliveMessage:116 : Sending keepalive request to client 0x19f33b0
>2013-02-27 02:25:12.061+00004752: debug : virKeepAliveMessage:119 : RPC_KEEPALIVE_SEND: ka=0x19f3db0 client=0x19f33b0 prog=1801807216 vers=1 proc=1
>2013-02-27 02:25:12.061+00004752: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=2 frequency=3000
>2013-02-27 02:25:12.061+00004752: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -728332192
>2013-02-27 02:25:12.061+00004752: debug : virObjectRef:168 : OBJECT_REF: obj=0x19f3db0
>2013-02-27 02:25:12.061+00004752: debug : virNetServerClientSendMessageLocked:1119 : msg=0x19f2bc0 proc=1 len=28 offset=0
>2013-02-27 02:25:12.061+00004752: debug : virNetServerClientSendMessageLocked:1127 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x19f33b0 len=28 prog=1801807216 vers=1 proc=1 type=2 status=0 serial=0
>2013-02-27 02:25:12.061+00004752: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x19f5a70 tx=0x19f2bc0
>2013-02-27 02:25:12.061+00004752: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2013-02-27 02:25:12.061+00004752: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=3
>2013-02-27 02:25:12.061+00004752: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -728332192
>2013-02-27 02:25:12.061+00004752: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x19f3db0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCleanupTimeouts:501 : Cleanup 9
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2013-02-27 02:25:12.061+00004752: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCleanupTimeouts:501 : Cleanup 9
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=19 e=5 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=24 e=25 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=23 e=25 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=22 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=27 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=13, f=30 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 9 timers
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931915061
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931913155
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931914606
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931914508
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCalculateTimeout:346 : Timeout at 1361931913155 due in 1094 ms
>2013-02-27 02:25:12.061+00004752: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=13 timeout=1094
>2013-02-27 02:25:12.061+00004752: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchTimeouts:410 : Dispatch 9
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:455 : Dispatch 13
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=2
>2013-02-27 02:25:12.061+00004752: debug : virNetMessageFree:73 : msg=0x19f2bc0 nfds=0 cb=(nil)
>2013-02-27 02:25:12.061+00004752: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x19f5a70 tx=(nil)
>2013-02-27 02:25:12.061+00004752: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-02-27 02:25:12.061+00004752: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
>2013-02-27 02:25:12.061+00004752: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -728332192
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=12 w=13
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCleanupTimeouts:501 : Cleanup 9
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2013-02-27 02:25:12.061+00004752: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCleanupTimeouts:501 : Cleanup 9
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=19 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=24 e=25 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=23 e=25 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=22 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=27 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=13, f=30 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 9 timers
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931915061
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931913155
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931914606
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931914508
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCalculateTimeout:346 : Timeout at 1361931913155 due in 1094 ms
>2013-02-27 02:25:12.061+00004752: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=13 timeout=1094
>2013-02-27 02:25:12.061+00004752: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchTimeouts:410 : Dispatch 9
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:455 : Dispatch 13
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=1
>2013-02-27 02:25:12.061+00004752: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2013-02-27 02:25:12.061+00004752: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x19f5a70 tx=(nil)
>2013-02-27 02:25:12.061+00004752: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-02-27 02:25:12.061+00004752: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
>2013-02-27 02:25:12.061+00004752: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -728332192
>2013-02-27 02:25:12.061+00004752: debug : virNetServerClientDispatchRead:875 : RPC_SERVER_CLIENT_MSG_RX: client=0x19f33b0 len=28 prog=1801807216 vers=1 proc=2 type=2 status=0 serial=0
>2013-02-27 02:25:12.061+00004752: debug : virKeepAliveCheckMessage:384 : ka=0x19f3db0, client=0x19f33b0, msg=0x19f5a70
>2013-02-27 02:25:12.061+00004752: debug : virKeepAliveCheckMessage:401 : RPC_KEEPALIVE_RECEIVED: ka=0x19f3db0 client=0x19f33b0 prog=1801807216 vers=1 proc=2
>2013-02-27 02:25:12.061+00004752: debug : virKeepAliveCheckMessage:410 : Got keepalive response from client 0x19f33b0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=2 frequency=3000
>2013-02-27 02:25:12.061+00004752: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -728332192
>2013-02-27 02:25:12.061+00004752: debug : virNetMessageFree:73 : msg=0x19f5a70 nfds=0 cb=(nil)
>2013-02-27 02:25:12.061+00004752: debug : virNetMessageNew:45 : msg=0x19f5a70 tracked=1
>2013-02-27 02:25:12.061+00004752: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x19f5a70 tx=(nil)
>2013-02-27 02:25:12.061+00004752: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-02-27 02:25:12.061+00004752: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
>2013-02-27 02:25:12.061+00004752: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -728332192
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2013-02-27 02:25:12.061+00004752: debug : virEventPollDispatchHandles:469 : i=12 w=13
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCleanupTimeouts:501 : Cleanup 9
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2013-02-27 02:25:12.061+00004752: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCleanupTimeouts:501 : Cleanup 9
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=19 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=24 e=25 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=23 e=25 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=22 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=27 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=13, f=30 e=1 d=0
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 9 timers
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931915061
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931913155
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931914606
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931914508
>2013-02-27 02:25:12.061+00004752: debug : virEventPollCalculateTimeout:346 : Timeout at 1361931913155 due in 1094 ms
>2013-02-27 02:25:12.061+00004752: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=13 timeout=1094
>2013-02-27 02:25:12.189+00004752: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchTimeouts:410 : Dispatch 9
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:455 : Dispatch 13
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=1
>2013-02-27 02:25:12.189+00004752: debug : virNetMessageDecodeLength:152 : Got length, now need 36 total (32 more)
>2013-02-27 02:25:12.189+00004752: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x19f5f60 tx=(nil)
>2013-02-27 02:25:12.189+00004752: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-02-27 02:25:12.189+00004752: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2013-02-27 02:25:12.189+00004752: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -728332192
>2013-02-27 02:25:12.189+00004752: debug : virNetServerClientDispatchRead:875 : RPC_SERVER_CLIENT_MSG_RX: client=0x19f2890 len=36 prog=536903814 vers=1 proc=23 type=0 status=0 serial=6
>2013-02-27 02:25:12.189+00004752: debug : virKeepAliveCheckMessage:384 : ka=0x19f3150, client=0x19f2890, msg=0x19f5f60
>2013-02-27 02:25:12.189+00004752: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=4 frequency=3000
>2013-02-27 02:25:12.189+00004752: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -728332192
>2013-02-27 02:25:12.189+00004752: debug : virObjectRef:168 : OBJECT_REF: obj=0x19f2890
>2013-02-27 02:25:12.189+00004752: debug : virNetServerDispatchNewMessage:218 : server=0x19ebd40 client=0x19f2890 message=0x19f5f60
>2013-02-27 02:25:12.189+00004752: debug : virObjectRef:168 : OBJECT_REF: obj=0x19f6f60
>2013-02-27 02:25:12.189+00004752: debug : virNetMessageNew:45 : msg=0x19f64c0 tracked=1
>2013-02-27 02:25:12.189+00004752: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x19f64c0 tx=(nil)
>2013-02-27 02:25:12.189+00004752: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-02-27 02:25:12.189+00004755: debug : virNetServerHandleJob:189 : server=0x19ebd40 client=0x19f2890 message=0x19f5f60 prog=0x19f6f60
>2013-02-27 02:25:12.189+00004752: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2013-02-27 02:25:12.189+00004752: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -728332192
>2013-02-27 02:25:12.189+00004755: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=6 proc=23
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=12 w=13
>2013-02-27 02:25:12.189+00004755: debug : remoteDispatchDomainLookupByNameHelper:3084 : server=0x19ebd40 client=0x19f2890 msg=0x19f5f60 rerr=0x7f70cd6d3b80 args=0x7f70a80c3fb0 ret=0x7f70a80c4010
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCleanupTimeouts:501 : Cleanup 9
>2013-02-27 02:25:12.189+00004755: debug : virDomainLookupByName:2117 : conn=0x7f70a0000bd0, name=aaa
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2013-02-27 02:25:12.189+00004752: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCleanupTimeouts:501 : Cleanup 9
>2013-02-27 02:25:12.189+00004755: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f70a80c3fd0 classname=virDomain
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2013-02-27 02:25:12.189+00004755: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f70a0000bd0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-02-27 02:25:12.189+00004755: debug : virDomainFree:2281 : dom=0x7f70a80c3fd0, (VM: name=aaa, uuid=f33d9914-4add-c75f-3502-3cd73ea3b795)
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-02-27 02:25:12.189+00004755: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f70a80c3fd0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-02-27 02:25:12.189+00004755: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f70a80c3fd0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-02-27 02:25:12.189+00004755: debug : virDomainDispose:225 : release domain 0x7f70a80c3fd0 aaa f33d9914-4add-c75f-3502-3cd73ea3b795
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=19 e=1 d=0
>2013-02-27 02:25:12.189+00004755: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f70a0000bd0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=24 e=25 d=0
>2013-02-27 02:25:12.189+00004755: debug : virNetMessageEncodePayload:358 : Encode length as 56
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=23 e=25 d=0
>2013-02-27 02:25:12.189+00004755: debug : virNetServerClientSendMessageLocked:1119 : msg=0x19f5f60 proc=23 len=56 offset=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=22 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=27 e=1 d=0
>2013-02-27 02:25:12.189+00004755: debug : virNetServerClientSendMessageLocked:1127 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x19f2890 len=56 prog=536903814 vers=1 proc=23 type=1 status=0 serial=6
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=13, f=30 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 9 timers
>2013-02-27 02:25:12.189+00004755: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x19f64c0 tx=0x19f5f60
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931915061
>2013-02-27 02:25:12.189+00004755: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931915189
>2013-02-27 02:25:12.189+00004755: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=3
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931914606
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931914508
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCalculateTimeout:346 : Timeout at 1361931914508 due in 2319 ms
>2013-02-27 02:25:12.189+00004752: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=13 timeout=2319
>2013-02-27 02:25:12.189+00004755: debug : virEventPollInterruptLocked:701 : Interrupting
>2013-02-27 02:25:12.189+00004755: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x19f6f60
>2013-02-27 02:25:12.189+00004752: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-02-27 02:25:12.189+00004755: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x19f2890
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchTimeouts:410 : Dispatch 9
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:455 : Dispatch 13
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=12 w=13
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCleanupTimeouts:501 : Cleanup 9
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2013-02-27 02:25:12.189+00004752: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCleanupTimeouts:501 : Cleanup 9
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=19 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=24 e=25 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=23 e=25 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=22 e=5 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=27 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=13, f=30 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 9 timers
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931915061
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931915189
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931914606
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931914508
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCalculateTimeout:346 : Timeout at 1361931914508 due in 2319 ms
>2013-02-27 02:25:12.189+00004752: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=13 timeout=2319
>2013-02-27 02:25:12.189+00004752: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchTimeouts:410 : Dispatch 9
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:455 : Dispatch 13
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=2
>2013-02-27 02:25:12.189+00004752: debug : virNetMessageFree:73 : msg=0x19f5f60 nfds=0 cb=(nil)
>2013-02-27 02:25:12.189+00004752: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x19f64c0 tx=(nil)
>2013-02-27 02:25:12.189+00004752: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-02-27 02:25:12.189+00004752: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2013-02-27 02:25:12.189+00004752: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -728332192
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=12 w=13
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCleanupTimeouts:501 : Cleanup 9
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2013-02-27 02:25:12.189+00004752: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCleanupTimeouts:501 : Cleanup 9
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=19 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=24 e=25 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=23 e=25 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=22 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=27 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=13, f=30 e=1 d=0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 9 timers
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931915061
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931915189
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931914606
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931914508
>2013-02-27 02:25:12.189+00004752: debug : virEventPollCalculateTimeout:346 : Timeout at 1361931914508 due in 2319 ms
>2013-02-27 02:25:12.189+00004752: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=13 timeout=2319
>2013-02-27 02:25:12.189+00004752: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchTimeouts:410 : Dispatch 9
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:455 : Dispatch 13
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2013-02-27 02:25:12.189+00004752: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=1
>2013-02-27 02:25:12.189+00004752: debug : virNetMessageDecodeLength:152 : Got length, now need 56 total (52 more)
>2013-02-27 02:25:12.189+00004752: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x19f64c0 tx=(nil)
>2013-02-27 02:25:12.189+00004752: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-02-27 02:25:12.189+00004752: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2013-02-27 02:25:12.189+00004752: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -728332192
>2013-02-27 02:25:12.189+00004752: debug : virNetServerClientDispatchRead:875 : RPC_SERVER_CLIENT_MSG_RX: client=0x19f2890 len=56 prog=536903814 vers=1 proc=12 type=0 status=0 serial=7
>2013-02-27 02:25:12.189+00004752: debug : virKeepAliveCheckMessage:384 : ka=0x19f3150, client=0x19f2890, msg=0x19f64c0
>2013-02-27 02:25:12.189+00004752: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=4 frequency=3000
>2013-02-27 02:25:12.189+00004752: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -728332192
>2013-02-27 02:25:12.189+00004752: debug : virObjectRef:168 : OBJECT_REF: obj=0x19f2890
>2013-02-27 02:25:12.189+00004752: debug : virNetServerDispatchNewMessage:218 : server=0x19ebd40 client=0x19f2890 message=0x19f64c0
>2013-02-27 02:25:12.189+00004752: debug : virObjectRef:168 : OBJECT_REF: obj=0x19f6f60
>2013-02-27 02:25:12.189+00004752: debug : virNetMessageNew:45 : msg=0x19f31e0 tracked=1
>2013-02-27 02:25:12.190+00004752: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x19f31e0 tx=(nil)
>2013-02-27 02:25:12.190+00004752: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-02-27 02:25:12.190+00004752: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2013-02-27 02:25:12.190+00004752: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -728332192
>2013-02-27 02:25:12.190+00004752: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2013-02-27 02:25:12.190+00004752: debug : virEventPollDispatchHandles:469 : i=12 w=13
>2013-02-27 02:25:12.190+00004752: debug : virEventPollCleanupTimeouts:501 : Cleanup 9
>2013-02-27 02:25:12.190+00004752: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2013-02-27 02:25:12.190+00004752: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-02-27 02:25:12.190+00004752: debug : virEventPollCleanupTimeouts:501 : Cleanup 9
>2013-02-27 02:25:12.190+00004752: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2013-02-27 02:25:12.190+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-02-27 02:25:12.190+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-02-27 02:25:12.190+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-02-27 02:25:12.190+00004753: debug : virNetServerHandleJob:189 : server=0x19ebd40 client=0x19f2890 message=0x19f64c0 prog=0x19f6f60
>2013-02-27 02:25:12.190+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-02-27 02:25:12.190+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-02-27 02:25:12.190+00004753: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=7 proc=12
>2013-02-27 02:25:12.190+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-02-27 02:25:12.190+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-02-27 02:25:12.190+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=19 e=1 d=0
>2013-02-27 02:25:12.190+00004753: debug : remoteDispatchDomainDestroyHelper:1254 : server=0x19ebd40 client=0x19f2890 msg=0x19f64c0 rerr=0x7f70cead5b80 args=0x7f70b80c13e0 ret=0x7f70b80c1380
>2013-02-27 02:25:12.190+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=24 e=25 d=0
>2013-02-27 02:25:12.190+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=23 e=25 d=0
>2013-02-27 02:25:12.190+00004753: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f70b80c13a0 classname=virDomain
>2013-02-27 02:25:12.190+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=22 e=1 d=0
>2013-02-27 02:25:12.190+00004753: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f70a0000bd0
>2013-02-27 02:25:12.190+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=27 e=1 d=0
>2013-02-27 02:25:12.190+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=13, f=30 e=1 d=0
>2013-02-27 02:25:12.190+00004752: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 9 timers
>2013-02-27 02:25:12.190+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931915061
>2013-02-27 02:25:12.190+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931915189
>2013-02-27 02:25:12.190+00004753: debug : virDomainDestroy:2172 : dom=0x7f70b80c13a0, (VM: name=aaa, uuid=f33d9914-4add-c75f-3502-3cd73ea3b795)
>2013-02-27 02:25:12.190+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931914606
>2013-02-27 02:25:12.190+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931914508
>2013-02-27 02:25:12.190+00004752: debug : virEventPollCalculateTimeout:346 : Timeout at 1361931914508 due in 2318 ms
>2013-02-27 02:25:12.190+00004752: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=13 timeout=2318
>2013-02-27 02:25:12.190+00004753: debug : qemuProcessKill:4104 : vm=aaa pid=4847 flags=1
>2013-02-27 02:25:12.193+00004752: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-02-27 02:25:12.193+00004752: debug : virEventPollDispatchTimeouts:410 : Dispatch 9
>2013-02-27 02:25:12.193+00004752: debug : virEventPollDispatchHandles:455 : Dispatch 13
>2013-02-27 02:25:12.193+00004752: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-02-27 02:25:12.193+00004752: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-02-27 02:25:12.193+00004752: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-02-27 02:25:12.193+00004752: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-02-27 02:25:12.193+00004752: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-02-27 02:25:12.193+00004752: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-02-27 02:25:12.193+00004752: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-02-27 02:25:12.193+00004752: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2013-02-27 02:25:12.193+00004752: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2013-02-27 02:25:12.193+00004752: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=9 events=1
>2013-02-27 02:25:12.193+00004752: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f70a800c3a0
>2013-02-27 02:25:12.193+00004752: debug : qemuMonitorIOProcess:353 : QEMU_MONITOR_IO_PROCESS: mon=0x7f70a800c3a0 buf={"timestamp": {"seconds": 1361931912, "microseconds": 193180}, "event": "SHUTDOWN"}
> len=85
>2013-02-27 02:25:12.193+00004752: debug : qemuMonitorJSONIOProcessLine:152 : Line [{"timestamp": {"seconds": 1361931912, "microseconds": 193180}, "event": "SHUTDOWN"}]
>2013-02-27 02:25:12.193+00004752: debug : virJSONValueFromString:975 : string={"timestamp": {"seconds": 1361931912, "microseconds": 193180}, "event": "SHUTDOWN"}
>2013-02-27 02:25:12.193+00004752: debug : virJSONParserHandleStartMap:852 : parser=0x7fff44efee70
>2013-02-27 02:25:12.193+00004752: debug : virJSONParserHandleMapKey:833 : parser=0x7fff44efee70 key=0x19f3252
>2013-02-27 02:25:12.193+00004752: debug : virJSONParserHandleStartMap:852 : parser=0x7fff44efee70
>2013-02-27 02:25:12.193+00004752: debug : virJSONParserHandleMapKey:833 : parser=0x7fff44efee70 key=0x19f3260
>2013-02-27 02:25:12.193+00004752: debug : virJSONParserHandleNumber:792 : parser=0x7fff44efee70 str=(null)
>2013-02-27 02:25:12.193+00004752: debug : virJSONParserHandleMapKey:833 : parser=0x7fff44efee70 key=0x19f3277
>2013-02-27 02:25:12.193+00004752: debug : virJSONParserHandleNumber:792 : parser=0x7fff44efee70 str=(null)
>2013-02-27 02:25:12.193+00004752: debug : virJSONParserHandleEndMap:881 : parser=0x7fff44efee70
>2013-02-27 02:25:12.193+00004752: debug : virJSONParserHandleMapKey:833 : parser=0x7fff44efee70 key=0x19f3290
>2013-02-27 02:25:12.193+00004752: debug : virJSONParserHandleString:813 : parser=0x7fff44efee70 str=0x19f3299
>2013-02-27 02:25:12.193+00004752: debug : virJSONParserHandleEndMap:881 : parser=0x7fff44efee70
>2013-02-27 02:25:12.193+00004752: debug : virJSONValueFromString:1019 : result=0x19cf800
>2013-02-27 02:25:12.193+00004752: debug : qemuMonitorJSONIOProcessLine:167 : QEMU_MONITOR_RECV_EVENT: mon=0x7f70a800c3a0 event={"timestamp": {"seconds": 1361931912, "microseconds": 193180}, "event": "SHUTDOWN"}
>2013-02-27 02:25:12.193+00004752: debug : qemuMonitorJSONIOProcessEvent:124 : mon=0x7f70a800c3a0 obj=0x19cf800
>2013-02-27 02:25:12.193+00004752: debug : qemuMonitorJSONIOProcessEvent:138 : handle SHUTDOWN handler=0x4af1d0 data=(nil)
>2013-02-27 02:25:12.193+00004752: debug : qemuMonitorEmitShutdown:987 : mon=0x7f70a800c3a0
>2013-02-27 02:25:12.193+00004752: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f70a800c3a0
>2013-02-27 02:25:12.193+00004752: debug : qemuProcessHandleShutdown:638 : vm=0x7f70c0183920
>2013-02-27 02:25:12.390+00004753: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f70c0183920
>2013-02-27 02:25:12.390+00004753: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f70c0183920
>2013-02-27 02:25:12.390+00004753: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f70c0183920
>2013-02-27 02:25:12.390+00004752: debug : qemuProcessHandleShutdown:655 : Transitioned guest aaa to shutdown state
>2013-02-27 02:25:12.438+00004752: debug : virFileClose:72 : Closed fd 33
>2013-02-27 02:25:12.438+00004752: debug : qemuAgentNotifyEvent:1263 : mon=0x7f70a800b390 event=1
>2013-02-27 02:25:12.438+00004752: warning : qemuAgentNotifyEvent:1274 : Received unexpected event 1
>2013-02-27 02:25:12.438+00004752: debug : qemuProcessKill:4104 : vm=aaa pid=4847 flags=2
>2013-02-27 02:25:12.438+00004752: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=9 frequency=0
>2013-02-27 02:25:12.438+00004752: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -728332192
>2013-02-27 02:25:12.438+00004752: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f70a800c3a0
>2013-02-27 02:25:12.438+00004752: debug : qemuMonitorJSONIOProcess:223 : Total used 85 bytes out of 85 available in buffer
>2013-02-27 02:25:12.438+00004752: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=13
>2013-02-27 02:25:12.438+00004752: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -728332192
>2013-02-27 02:25:12.438+00004752: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f70a800c3a0
>2013-02-27 02:25:12.438+00004752: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2013-02-27 02:25:12.438+00004752: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2013-02-27 02:25:12.438+00004752: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2013-02-27 02:25:12.438+00004752: debug : virEventPollDispatchHandles:469 : i=12 w=13
>2013-02-27 02:25:12.438+00004752: debug : virEventPollCleanupTimeouts:501 : Cleanup 9
>2013-02-27 02:25:12.438+00004752: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2013-02-27 02:25:12.438+00004752: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-02-27 02:25:12.438+00004752: debug : virEventPollCleanupTimeouts:501 : Cleanup 9
>2013-02-27 02:25:12.438+00004752: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2013-02-27 02:25:12.438+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-02-27 02:25:12.438+002013-02-27 02:25:12.444+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=13, f=30 e=5 d=0
>2013-02-27 02:25:12.444+00004752: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 9 timers
>2013-02-27 02:25:12.444+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931915061
>2013-02-27 02:25:12.444+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931915189
>2013-02-27 02:25:12.444+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931914606
>2013-02-27 02:25:12.444+00004752: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1361931914508
>2013-02-27 02:25:12.444+00004752: debug : virEventPollCalculateTimeout:346 : Timeout at 1361931914508 due in 2064 ms
>2013-02-27 02:25:12.444+00004752: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=2064
>2013-02-27 02:25:12.444+00004752: debug : virEventPollRunOnce:625 : Poll got 4 event(s)
>2013-02-27 02:25:12.444+00004752: debug : virEventPollDispatchTimeouts:410 : Dispatch 9
>2013-02-27 02:25:12.444+00004752: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2013-02-27 02:25:12.444+00004752: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-02-27 02:25:12.444+00004752: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-02-27 02:25:12.444+00004752: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-02-27 02:25:12.444+00004752: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-02-27 02:25:12.444+00004752: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-02-27 02:25:12.444+00004752: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-02-27 02:25:12.444+00004752: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=6 events=1
>2013-02-27 02:25:12.444+00004752: debug : virNetlinkEventCallback:346 : dispatching to max 0 clients, called from event watch 6
>2013-02-27 02:25:12.444+00004752: debug : virNetlinkEventCallback:359 : event not handled.
>2013-02-27 02:25:12.444+00004752: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-02-27 02:25:12.444+00004752: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=7 events=1
>2:25:12.438+00004752: debug : virEventPollDispatchTimeouts:410 : Dispatch 9
>2013-02-27 02:25:12.438+00004752: debug : virEventPollDispatchTimeouts:433 : EVENT_POLL_DISPATCH_TIMEOUT: timer=9
>2013-02-27 02:25:12.438+00004752: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=9 frequency=-1
>2013-02-27 02:25:12.438+00004752: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -728332192
>2013-02-27 02:25:12.438+00004752: debug : virObjectNew:110 : OBJECT_NEW: obj=0x19f3bf0 classname=virDomain
>2013-02-27 02:25:12.438+00004752: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f70b8000b80
>2013-02-27 02:25:12.438+00004752: debug : remoteRelayDomainEventLifecycle:128 : Relaying domain lifecycle event 6 0
>2013-02-27 02:25:12.438+00004752: debug : virNetMessageNew:45 : msg=0x19f61a0 tracked=0
>2013-02-27 02:25:12.438+00004752: debug : virNetMessageEncodePayload:358 : Encode length as 64
>2013-02-27 02:25:12.438+00004752: debug : remoteDispatchDomainEventSend:3237 : Queue event 107 64
>2013-02-27 02:25:12.438+00004752: debug : virNetServerClientSendMessageLocked:1119 : msg=0x19f61a0 proc=107 len=64 offset=0
>2013-02-27 02:25:12.438+00004752: debug : virNetServerClientSendMessageLocked:1127 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x19f71a0 len=64 prog=536903814 vers=1 proc=107 type=2 status=0 serial=1
>2013-02-27 02:25:12.438+00004752: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x19f2ca0 tx=0x19f61a0
>2013-02-27 02:25:12.438+00004752: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2013-02-27 02:25:12.438+00004752: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=13 events=3
>2013-02-27 02:25:12.438+00004752: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -728332192
>2013-02-27 02:25:12.438+00004752: debug : virDomainFree:2281 : dom=0x19f3bf0, (VM: name=aaa, uuid=f33d9914-4add-c75f-3502-3cd73ea3b795)
>2013-02-27 02:25:12.438+00004752: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x19f3bf0
>2013-02-27 02:25:12.438+00004752: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x19f3bf0
>2013-02-27 02:25:12.438+00004752: debug : virDomainDispose:225 : release domain 0x19f3bf0 aaa f33d9914-4add-c75f-3502-3cd73ea3b795
>2013-02-27 02:25:12.438+00004752: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f70b8000b80
>2013-02-27 02:25:12.438+00004752: debug : virEventPollDispatchHandles:455 : Dispatch 13
>2013-02-27 02:25:12.438+00004752: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-02-27 02:25:12.438+00004752: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-02-27 02:25:12.438+00004752: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-02-27 02:25:12.438+00004752: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-02-27 02:25:12.438+00004752: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-02-27 02:25:12.438+00004752: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-02-27 02:25:12.438+00004752: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=6 events=1
>2013-02-27 02:25:12.444+00004752: debug : virNetlinkEventCallback:346 : dispatching to max 0 clients, called from event watch 6
>2013-02-27 02:25:12.444+00004752: debug : virNetlinkEventCallback:359 : event not handled.
>2013-02-27 02:25:12.444+00004752: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-02-27 02:25:12.444+00004752: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=7 events=1
>2013-02-27 02:25:12.444+00004752: debug : udevEventHandleCallback:1467 : udev action: 'remove'
>2013-02-27 02:25:12.444+00004752: debug : udevRemoveOneDevice:1218 : Failed to find device to remove that has udev name '/sys/devices/virtual/net/vnet0/queues/tx-0'
>2013-02-27 02:25:12.444+00004752: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2013-02-27 02:25:12.444+00004752: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2013-02-27 02:25:12.444+00004752: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=9 events=9
>2013-02-27 02:25:12.444+00004752: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f70a800c3a0
>2013-02-27 02:25:12.444+00004752: error : qemuMonitorIO:613 : internal error End of file from monitor
>2013-02-27 02:25:12.444+00004752: debug : qemuMonitorIO:646 : Error on monitor internal error End of file from monitor
>2013-02-27 02:25:12.444+00004752: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=9 events=12
>2013-02-27 02:25:12.444+00004752: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -728332192
>2013-02-27 02:25:12.444+00004752: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f70a800c3a0
>2013-02-27 02:25:12.444+00004752: debug : qemuMonitorIO:669 : Triggering EOF callback
>2013-02-27 02:25:12.444+00004752: debug : qemuProcessHandleMonitorEOF:283 : Received EOF on 0x7f70c0183920 'aaa'
>2013-02-27 02:25:12.444+00004752: debug : qemuProcessHandleMonitorEOF:291 : Domain is being destroyed, EOF is expected
>2013-02-27 02:25:12.444+00004752: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2013-02-27 02:25:12.444+00004752: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=10 events=9
>2013-02-27 02:25:12.444+00004752: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f70a800b390
>2013-02-27 02:25:12.444+00004752: error : qemuAgentIO:642 : internal error End of file from monitor
>2013-02-27 02:25:12.444+00004752: debug : qemuAgentIO:675 : Error on monitor internal error End of file from monitor
>2013-02-27 02:25:12.444+00004752: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=12
>2013-02-27 02:25:12.444+00004752: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -728332192
>2013-02-27 02:25:12.444+00004752: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f70a800b390
>2013-02-27 02:25:12.444+00004752: debug : qemuAgentIO:698 : Triggering EOF callback
>2013-02-27 02:25:12.444+00004752: debug : qemuProcessHandleAgentEOF:125 : Received EOF from agent on 0x7f70c0183920 'aaa'
>2013-02-27 02:25:12.444+00004752: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f70a800b390
>2013-02-27 02:25:12.444+00004752: debug : qemuAgentClose:814 : mon=0x7f70a800b390
>2013-02-27 02:25:12.444+00004752: debug : virEventPollRemoveHandle:175 : EVENT_POLL_REMOVE_HANDLE: watch=10
>2013-02-27 02:25:12.444+00004752: debug : virEventPollRemoveHandle:188 : mark delete 9 23
>2013-02-27 02:25:12.444+00004752: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -728332192
>2013-02-27 02:25:12.444+00004752: debug : virFileClose:72 : Closed fd 23
>2013-02-27 02:25:12.444+00004752: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f70a800b390
>2013-02-27 02:25:12.444+00004752: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2013-02-27 02:25:12.444+00004752: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2013-02-27 02:25:12.444+00004752: debug : virEventPollDispatchHandles:469 : i=12 w=13
>2013-02-27 02:25:12.444+00004752: debug : virEventPollCleanupTimeouts:501 : Cleanup 9
>2013-02-27 02:25:12.444+00004752: debug : virEventPollCleanupHandles:549 : Cleanup 13
>2013-02-27 02:25:12.444+00004752: debug : virEventPollCleanupHandles:562 : EVENT_POLL_PURGE_HANDLE: watch=10
>2013-02-27 02:25:12.444+00004752: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f70a800b390
>2013-02-27 02:25:12.444+00004752: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f70a800b390
>2013-02-27 02:25:12.444+00004752: debug : qemuAgentDispose:169 : mon=0x7f70a800b390
>2013-02-27 02:25:12.444+00004752: debug : qemuProcessHandleAgentDestroy:171 : Received destroy agent=0x7f70a800b390 vm=0x7f70c0183920
>2013-02-27 02:25:12.444+00004752: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f70c0183920
>2013-02-27 02:25:12.444+00004752: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-02-27 02:25:12.444+00004752: debug : virEventPollCleanupTimeouts:501 : Cleanup 9
>2013-02-27 02:25:12.444+00004752: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2013-02-27 02:25:12.444+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-02-27 02:25:12.444+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-02-27 02:25:12.444+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-02-27 02:25:12.444+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-02-27 02:25:12.444+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-02-27 02:25:12.444+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-02-27 02:25:12.444+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-02-27 02:25:12.444+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=19 e=1 d=0
>2013-02-27 02:25:12.444+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=24 e=24 d=0
>2013-02-27 02:25:12.444+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=11, f=22 e=1 d=0
>2013-02-27 02:25:12.444+00004752: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=12, f=27 e=1 d=0
>
>
>     ====== end of log =====
>

Actions: View

Attachments on bug 915636: 702714 | 703233 | 703235 | 703268