Attachment 908005 Details for Bug 1105954 – The log about libvirtd

The log about libvirtd

libvirtd.log (text/plain), 64.11 KB, created by zhenfeng wang on 2014-06-12 08:18:33 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: zhenfeng wang

Created: 2014-06-12 08:18:33 UTC

Size: 64.11 KB

patch

obsolete

>    ====== start of log =====
>
>Detaching from program: /usr/sbin/libvirtd, process 6265
>6265: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-06-12 07:56:01.621+00006265: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-06-12 07:56:01.621+00006265: debug : virEventPollDispatchHandles:469 : i=7 w=10
>2014-06-12 07:56:01.621+00006265: debug : virEventPollDispatchHandles:469 : i=8 w=11
>2014-06-12 07:56:01.621+00006265: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=1
>2014-06-12 07:56:01.621+00006265: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2014-06-12 07:56:01.621+00006265: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x796670 tx=(nil)
>2014-06-12 07:56:01.621+00006265: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-06-12 07:56:01.621+00006265: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-06-12 07:56:01.621+00006265: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -140986272
>2014-06-12 07:56:01.621+00006265: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x79eb00 len=28 prog=536903814 vers=1 proc=110 type=0 status=0 serial=3
>2014-06-12 07:56:01.621+00006265: debug : virKeepAliveCheckMessage:384 : ka=0x79eff0, client=0x79eb00, msg=0x796670
>2014-06-12 07:56:01.621+00006265: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=6 frequency=5000
>2014-06-12 07:56:01.621+00006265: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -140986272
>2014-06-12 07:56:01.621+00006265: debug : virObjectRef:168 : OBJECT_REF: obj=0x79eb00
>2014-06-12 07:56:01.621+00006265: debug : virNetServerDispatchNewMessage:218 : server=0x799ff0 client=0x79eb00 message=0x796670
>2014-06-12 07:56:01.621+00006265: debug : virObjectRef:168 : OBJECT_REF: obj=0x7a2a70
>2014-06-12 07:56:01.621+00006265: debug : virNetMessageNew:45 : msg=0x79f510 tracked=1
>2014-06-12 07:56:01.621+00006265: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x79f510 tx=(nil)
>2014-06-12 07:56:01.621+00006265: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-06-12 07:56:01.621+00006265: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-06-12 07:56:01.622+00006265: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -140986272
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 07:56:01.622+00006265: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=9 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=11 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=14 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=15 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=21 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=22 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2014-06-12 07:56:01.622+00006274: debug : virNetServerHandleJob:189 : server=0x799ff0 client=0x79eb00 message=0x796670 prog=0x7a2a70
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=10, f=26 e=25 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=11, f=19 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-06-12 07:56:01.622+00006274: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=3 proc=110
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1402559766621
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCalculateTimeout:346 : Timeout at 1402559766621 due in 4999 ms
>2014-06-12 07:56:01.622+00006274: debug : remoteDispatchGetURIHelper:7297 : server=0x799ff0 client=0x79eb00 msg=0x796670 rerr=0x7fffecaf0b80 args=0x7fffc8000970 ret=0x7fffc80008c0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=4999
>2014-06-12 07:56:01.622+00006274: debug : virConnectGetURI:1700 : conn=0x7fffdc007250
>2014-06-12 07:56:01.622+00006274: debug : virNetMessageEncodePayload:358 : Encode length as 48
>2014-06-12 07:56:01.622+00006274: debug : virNetServerClientSendMessageLocked:1122 : msg=0x796670 proc=110 len=48 offset=0
>2014-06-12 07:56:01.622+00006274: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x79eb00 len=48 prog=536903814 vers=1 proc=110 type=1 status=0 serial=3
>2014-06-12 07:56:01.622+00006274: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x79f510 tx=0x796670
>2014-06-12 07:56:01.622+00006274: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-06-12 07:56:01.622+00006274: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=3
>2014-06-12 07:56:01.622+00006274: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-06-12 07:56:01.622+00006274: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7a2a70
>2014-06-12 07:56:01.622+00006274: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x79eb00
>2014-06-12 07:56:01.622+00006265: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=7 w=10
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=8 w=11
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 07:56:01.622+00006265: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=9 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=11 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=14 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=15 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=21 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=22 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=10, f=26 e=25 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=11, f=19 e=5 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1402559766621
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCalculateTimeout:346 : Timeout at 1402559766621 due in 4999 ms
>2014-06-12 07:56:01.622+00006265: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=4999
>2014-06-12 07:56:01.622+00006265: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=7 w=10
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=8 w=11
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=2
>2014-06-12 07:56:01.622+00006265: debug : virNetMessageFree:73 : msg=0x796670 nfds=0 cb=(nil)
>2014-06-12 07:56:01.622+00006265: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x79f510 tx=(nil)
>2014-06-12 07:56:01.622+00006265: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-06-12 07:56:01.622+00006265: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-06-12 07:56:01.622+00006265: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -140986272
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 07:56:01.622+00006265: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=9 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=11 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=14 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=15 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=21 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=22 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=10, f=26 e=25 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=11, f=19 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1402559766621
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCalculateTimeout:346 : Timeout at 1402559766621 due in 4999 ms
>2014-06-12 07:56:01.622+00006265: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=4999
>2014-06-12 07:56:01.622+00006265: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=7 w=10
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=8 w=11
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=1
>2014-06-12 07:56:01.622+00006265: debug : virNetMessageDecodeLength:152 : Got length, now need 40 total (36 more)
>2014-06-12 07:56:01.622+00006265: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x79f510 tx=(nil)
>2014-06-12 07:56:01.622+00006265: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-06-12 07:56:01.622+00006265: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-06-12 07:56:01.622+00006265: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -140986272
>2014-06-12 07:56:01.622+00006265: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x79eb00 len=40 prog=536903814 vers=1 proc=23 type=0 status=0 serial=4
>2014-06-12 07:56:01.622+00006265: debug : virKeepAliveCheckMessage:384 : ka=0x79eff0, client=0x79eb00, msg=0x79f510
>2014-06-12 07:56:01.622+00006265: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=6 frequency=5000
>2014-06-12 07:56:01.622+00006265: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -140986272
>2014-06-12 07:56:01.622+00006265: debug : virObjectRef:168 : OBJECT_REF: obj=0x79eb00
>2014-06-12 07:56:01.622+00006265: debug : virNetServerDispatchNewMessage:218 : server=0x799ff0 client=0x79eb00 message=0x79f510
>2014-06-12 07:56:01.622+00006265: debug : virObjectRef:168 : OBJECT_REF: obj=0x7a2a70
>2014-06-12 07:56:01.622+00006265: debug : virNetMessageNew:45 : msg=0x79fb60 tracked=1
>2014-06-12 07:56:01.622+00006265: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x79fb60 tx=(nil)
>2014-06-12 07:56:01.622+00006265: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-06-12 07:56:01.622+00006265: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-06-12 07:56:01.622+00006265: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -140986272
>2014-06-12 07:56:01.622+00006268: debug : virNetServerHandleJob:189 : server=0x799ff0 client=0x79eb00 message=0x79f510 prog=0x7a2a70
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 07:56:01.622+00006265: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-06-12 07:56:01.622+00006268: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=23
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=9 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=11 e=1 d=0
>2014-06-12 07:56:01.622+00006268: debug : remoteDispatchDomainLookupByNameHelper:3084 : server=0x799ff0 client=0x79eb00 msg=0x79f510 rerr=0x7ffff06f6b80 args=0x7fffe0009870 ret=0x7fffe0008fa0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=14 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=15 e=1 d=0
>2014-06-12 07:56:01.622+00006268: debug : virDomainLookupByName:2117 : conn=0x7fffdc007250, name=rhel6
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=21 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=22 e=1 d=0
>2014-06-12 07:56:01.622+00006268: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7fffe0008f60 classname=virDomain
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=10, f=26 e=25 d=0
>2014-06-12 07:56:01.622+00006268: debug : virObjectRef:168 : OBJECT_REF: obj=0x7fffdc007250
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=11, f=19 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1402559766622
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCalculateTimeout:346 : Timeout at 1402559766622 due in 5000 ms
>2014-06-12 07:56:01.622+00006268: debug : virDomainFree:2281 : dom=0x7fffe0008f60, (VM: name=rhel6, uuid=8a89d1b6-bfb6-ffa9-9664-4d8808d1a279)
>2014-06-12 07:56:01.622+00006265: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=5000
>2014-06-12 07:56:01.622+00006268: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fffe0008f60
>2014-06-12 07:56:01.622+00006268: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7fffe0008f60
>2014-06-12 07:56:01.622+00006268: debug : virDomainDispose:264 : release domain 0x7fffe0008f60 rhel6 8a89d1b6-bfb6-ffa9-9664-4d8808d1a279
>2014-06-12 07:56:01.622+00006268: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fffdc007250
>2014-06-12 07:56:01.622+00006268: debug : virNetMessageEncodePayload:358 : Encode length as 60
>2014-06-12 07:56:01.622+00006268: debug : virNetServerClientSendMessageLocked:1122 : msg=0x79f510 proc=23 len=60 offset=0
>2014-06-12 07:56:01.622+00006268: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x79eb00 len=60 prog=536903814 vers=1 proc=23 type=1 status=0 serial=4
>2014-06-12 07:56:01.622+00006268: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x79fb60 tx=0x79f510
>2014-06-12 07:56:01.622+00006268: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-06-12 07:56:01.622+00006268: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=3
>2014-06-12 07:56:01.622+00006268: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-06-12 07:56:01.622+00006268: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7a2a70
>2014-06-12 07:56:01.622+00006265: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-06-12 07:56:01.622+00006268: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x79eb00
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=7 w=10
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchHandles:469 : i=8 w=11
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 07:56:01.622+00006265: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=9 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=11 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=14 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=15 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=21 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=22 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=10, f=26 e=25 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=11, f=19 e=5 d=0
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1402559766622
>2014-06-12 07:56:01.622+00006265: debug : virEventPollCalculateTimeout:346 : Timeout at 1402559766622 due in 5000 ms
>2014-06-12 07:56:01.622+00006265: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=5000
>2014-06-12 07:56:01.622+00006265: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-06-12 07:56:01.622+00006265: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-06-12 07:56:01.623+00006265: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-06-12 07:56:01.623+00006265: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-06-12 07:56:01.623+00006265: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-06-12 07:56:01.623+00006265: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-06-12 07:56:01.623+00006265: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-06-12 07:56:01.623+00006265: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-06-12 07:56:01.623+00006265: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-06-12 07:56:01.623+00006265: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-06-12 07:56:01.623+00006265: debug : virEventPollDispatchHandles:469 : i=7 w=10
>2014-06-12 07:56:01.623+00006265: debug : virEventPollDispatchHandles:469 : i=8 w=11
>2014-06-12 07:56:01.623+00006265: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=2
>2014-06-12 07:56:01.623+00006265: debug : virNetMessageFree:73 : msg=0x79f510 nfds=0 cb=(nil)
>2014-06-12 07:56:01.623+00006265: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x79fb60 tx=(nil)
>2014-06-12 07:56:01.623+00006265: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-06-12 07:56:01.623+00006265: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-06-12 07:56:01.623+00006265: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -140986272
>2014-06-12 07:56:01.623+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 07:56:01.623+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 07:56:01.623+00006265: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-06-12 07:56:01.623+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 07:56:01.623+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 07:56:01.623+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=9 e=1 d=0
>2014-06-12 07:56:01.623+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=11 e=1 d=0
>2014-06-12 07:56:01.623+0000622014-06-12 08:04:21.777+00006265: debug : virEventPollRunOnce:625 : Poll got 0 event(s)
>2014-06-12 08:04:21.777+00006265: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-06-12 08:04:21.777+00006265: debug : virEventPollDispatchTimeouts:433 : EVENT_POLL_DISPATCH_TIMEOUT: timer=6
>2014-06-12 08:04:21.777+00006265: debug : virKeepAliveTimerInternal:148 : RPC_KEEPALIVE_TIMEOUT: ka=0x79eff0 client=0x79eb00 countToDeath=5 idle=500
>2014-06-12 08:04:21.777+00006265: debug : virNetMessageNew:45 : msg=0x79ed30 tracked=0
>2014-06-12 08:04:21.777+00006265: debug : virNetMessageEncodePayloadEmpty:448 : Encode length as 28
>2014-06-12 08:04:21.777+00006265: debug : virKeepAliveMessage:116 : Sending keepalive request to client 0x79eb00
>2014-06-12 08:04:21.777+00006265: debug : virKeepAliveMessage:119 : RPC_KEEPALIVE_SEND: ka=0x79eff0 client=0x79eb00 prog=1801807216 vers=1 proc=1
>2014-06-12 08:04:21.777+00006265: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=6 frequency=5000
>2014-06-12 08:04:21.777+00006265: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -140986272
>2014-06-12 08:04:21.777+00006265: debug : virObjectRef:168 : OBJECT_REF: obj=0x79eff0
>2014-06-12 08:04:21.777+00006265: debug : virNetServerClientSendMessageLocked:1122 : msg=0x79ed30 proc=1 len=28 offset=0
>2014-06-12 08:04:21.777+00006265: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x79eb00 len=28 prog=1801807216 vers=1 proc=1 type=2 status=0 serial=0
>2014-06-12 08:04:21.777+00006265: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x79ec50 tx=0x79ed30
>2014-06-12 08:04:21.777+00006265: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-06-12 08:04:21.777+00006265: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=3
>2014-06-12 08:04:21.777+00006265: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -140986272
>2014-06-12 08:04:21.777+00006265: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x79eff0
>2014-06-12 08:04:21.777+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 08:04:21.777+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 08:04:21.777+00006265: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-06-12 08:04:21.777+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 08:04:21.777+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 08:04:21.777+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=9 e=1 d=0
>2014-06-12 08:04:21.777+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=11 e=1 d=0
>2014-06-12 08:04:21.777+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=14 e=1 d=0
>2014-06-12 08:04:21.777+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=15 e=1 d=0
>2014-06-12 08:04:21.777+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=21 e=1 d=0
>2014-06-12 08:04:21.777+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=22 e=1 d=0
>2014-06-12 08:04:21.777+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2014-06-12 08:04:21.777+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=10, f=26 e=25 d=0
>2014-06-12 08:04:21.777+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=11, f=19 e=5 d=0
>2014-06-12 08:04:21.777+00006265: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-06-12 08:04:21.777+00006265: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1402560266777
>2014-06-12 08:04:21.777+00006265: debug : virEventPollCalculateTimeout:346 : Timeout at 1402560266777 due in 5000 ms
>2014-06-12 08:04:21.777+00006265: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=5000
>2014-06-12 08:04:21.777+00006265: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-06-12 08:04:21.777+00006265: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-06-12 08:04:21.777+00006265: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-06-12 08:04:21.777+00006265: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-06-12 08:04:21.777+00006265: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-06-12 08:04:21.777+00006265: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-06-12 08:04:21.777+00006265: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-06-12 08:04:21.777+00006265: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-06-12 08:04:21.777+00006265: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-06-12 08:04:21.777+00006265: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-06-12 08:04:21.777+00006265: debug : virEventPollDispatchHandles:469 : i=7 w=10
>2014-06-12 08:04:21.777+00006265: debug : virEventPollDispatchHandles:469 : i=8 w=11
>2014-06-12 08:04:21.777+00006265: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=2
>2014-06-12 08:04:21.777+00006265: debug : virNetMessageFree:73 : msg=0x79ed30 nfds=0 cb=(nil)
>2014-06-12 08:04:21.777+00006265: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x79ec50 tx=(nil)
>2014-06-12 08:04:21.777+00006265: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-06-12 08:04:21.777+00006265: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-06-12 08:04:21.777+00006265: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -140986272
>2014-06-12 08:04:21.777+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 08:04:21.777+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 08:04:21.777+00006265: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-06-12 08:04:21.777+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 08:04:21.777+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 08:04:21.777+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=9 e=1 d=0
>2014-06-12 08:04:21.777+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=11 e=1 d=0
>2014-06-12 08:04:21.777+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=14 e=1 d=0
>2014-06-12 08:04:21.777+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=15 e=1 d=0
>2014-06-12 08:04:21.777+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=21 e=1 d=0
>2014-06-12 08:04:21.777+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=22 e=1 d=0
>2014-06-12 08:04:21.777+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2014-06-12 08:04:21.777+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=10, f=26 e=25 d=0
>2014-06-12 08:04:21.777+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=11, f=19 e=1 d=0
>2014-06-12 08:04:21.777+00006265: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-06-12 08:04:21.777+00006265: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1402560266777
>2014-06-12 08:04:21.777+00006265: debug : virEventPollCalculateTimeout:346 : Timeout at 1402560266777 due in 5000 ms
>2014-06-12 08:04:21.777+00006265: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=5000
>2014-06-12 08:04:21.778+00006265: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-06-12 08:04:21.778+00006265: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-06-12 08:04:21.778+00006265: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-06-12 08:04:21.778+00006265: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-06-12 08:04:21.778+00006265: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-06-12 08:04:21.778+00006265: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-06-12 08:04:21.778+00006265: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-06-12 08:04:21.778+00006265: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-06-12 08:04:21.778+00006265: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-06-12 08:04:21.778+00006265: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-06-12 08:04:21.778+00006265: debug : virEventPollDispatchHandles:469 : i=7 w=10
>2014-06-12 08:04:21.778+00006265: debug : virEventPollDispatchHandles:469 : i=8 w=11
>2014-06-12 08:04:21.778+00006265: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=1
>2014-06-12 08:04:21.778+00006265: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2014-06-12 08:04:21.778+00006265: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x79ec50 tx=(nil)
>2014-06-12 08:04:21.778+00006265: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-06-12 08:04:21.778+00006265: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-06-12 08:04:21.778+00006265: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -140986272
>2014-06-12 08:04:21.778+00006265: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x79eb00 len=28 prog=1801807216 vers=1 proc=2 type=2 status=0 serial=0
>2014-06-12 08:04:21.778+00006265: debug : virKeepAliveCheckMessage:384 : ka=0x79eff0, client=0x79eb00, msg=0x79ec50
>2014-06-12 08:04:21.778+00006265: debug : virKeepAliveCheckMessage:401 : RPC_KEEPALIVE_RECEIVED: ka=0x79eff0 client=0x79eb00 prog=1801807216 vers=1 proc=2
>2014-06-12 08:04:21.778+00006265: debug : virKeepAliveCheckMessage:410 : Got keepalive response from client 0x79eb00
>2014-06-12 08:04:21.778+00006265: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=6 frequency=5000
>2014-06-12 08:04:21.778+00006265: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -140986272
>2014-06-12 08:04:21.778+00006265: debug : virNetMessageFree:73 : msg=0x79ec50 nfds=0 cb=(nil)
>2014-06-12 08:04:21.778+00006265: debug : virNetMessageNew:45 : msg=0x79ec50 tracked=1
>2014-06-12 08:04:21.778+00006265: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x79ec50 tx=(nil)
>2014-06-12 08:04:21.778+00006265: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-06-12 08:04:21.778+00006265: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-06-12 08:04:21.778+00006265: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -140986272
>2014-06-12 08:04:21.778+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 08:04:21.778+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 08:04:21.778+00006265: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-06-12 08:04:21.778+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 08:04:21.778+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 08:04:21.778+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=9 e=1 d=0
>2014-06-12 08:04:21.778+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=11 e=1 d=0
>2014-06-12 08:04:21.778+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=14 e=1 d=0
>2014-06-12 08:04:21.778+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=15 e=1 d=0
>2014-06-12 08:04:21.778+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=21 e=1 d=0
>2014-06-12 08:04:21.778+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=22 e=1 d=0
>2014-06-12 08:04:21.778+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2014-06-12 08:04:21.778+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=10, f=26 e=25 d=0
>2014-06-12 08:04:21.778+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=11, f=19 e=1 d=0
>2014-06-12 08:04:21.778+00006265: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-06-12 08:04:21.778+00006265: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1402560266778
>2014-06-12 08:04:21.778+00006265: debug : virEventPollCalculateTimeout:346 : Timeout at 1402560266778 due in 5000 ms
>2014-06-12 08:04:21.778+00006265: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=5000
>EventPollDispatchHandles:469 : i=8 w=11
>2014-06-12 07:56:01.623+00006265: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=2
>2014-06-12 07:56:01.623+00006265: debug : virNetMessageFree:73 : msg=0x79fb60 nfds=0 cb=(nil)
>2014-06-12 07:56:01.623+00006265: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x79f640 tx=(nil)
>2014-06-12 07:56:01.623+00006265: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-06-12 07:56:01.623+00006265: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-06-12 07:56:01.623+00006265: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -140986272
>2014-06-12 07:56:01.623+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 07:56:01.623+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 07:56:01.623+00006265: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-06-12 07:56:01.623+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 07:56:01.623+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 07:56:01.623+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=9 e=1 d=0
>2014-06-12 07:56:01.623+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=11 e=1 d=0
>2014-06-12 07:56:01.623+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=14 e=1 d=0
>2014-06-12 07:56:01.623+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=15 e=1 d=0
>2014-06-12 07:56:01.623+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=21 e=1 d=0
>2014-06-12 07:56:01.623+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=22 e=1 d=0
>2014-06-12 07:56:01.623+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2014-06-12 07:56:01.623+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=10, f=26 e=25 d=0
>2014-06-12 07:56:01.623+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=11, f=19 e=1 d=0
>2014-06-12 07:56:01.623+00006265: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-06-12 07:56:01.623+00006265: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1402559766623
>2014-06-12 07:56:01.623+00006265: debug : virEventPollCalculateTimeout:346 : Timeout at 1402559766623 due in 5000 ms
>2014-06-12 07:56:01.623+00006265: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=5000
>2014-06-12 07:56:01.624+00006265: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-06-12 07:56:01.624+00006265: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-06-12 07:56:01.624+00006265: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-06-12 07:56:01.624+00006265: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-06-12 07:56:01.624+00006265: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-06-12 07:56:01.624+00006265: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-06-12 07:56:01.624+00006265: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-06-12 07:56:01.624+00006265: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-06-12 07:56:01.624+00006265: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-06-12 07:56:01.624+00006265: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-06-12 07:56:01.624+00006265: debug : virEventPollDispatchHandles:469 : i=7 w=10
>2014-06-12 07:56:01.624+00006265: debug : virEventPollDispatchHandles:469 : i=8 w=11
>2014-06-12 07:56:01.624+00006265: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=11 events=1
>2014-06-12 07:56:01.624+00006265: debug : virNetMessageDecodeLength:152 : Got length, now need 80 total (76 more)
>2014-06-12 07:56:01.624+00006265: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x79f640 tx=(nil)
>2014-06-12 07:56:01.624+00006265: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-06-12 07:56:01.624+00006265: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-06-12 07:56:01.624+00006265: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -140986272
>2014-06-12 07:56:01.624+00006265: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x79eb00 len=80 prog=536903814 vers=1 proc=55 type=0 status=0 serial=6
>2014-06-12 07:56:01.624+00006265: debug : virKeepAliveCheckMessage:384 : ka=0x79eff0, client=0x79eb00, msg=0x79f640
>2014-06-12 07:56:01.624+00006265: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=6 frequency=5000
>2014-06-12 07:56:01.624+00006265: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -140986272
>2014-06-12 07:56:01.624+00006265: debug : virObjectRef:168 : OBJECT_REF: obj=0x79eb00
>2014-06-12 07:56:01.624+00006265: debug : virNetServerDispatchNewMessage:218 : server=0x799ff0 client=0x79eb00 message=0x79f640
>2014-06-12 07:56:01.624+00006265: debug : virObjectRef:168 : OBJECT_REF: obj=0x7a2a70
>2014-06-12 07:56:01.624+00006270: debug : virNetServerHandleJob:189 : server=0x799ff0 client=0x79eb00 message=0x79f640 prog=0x7a2a70
>2014-06-12 07:56:01.624+00006270: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=6 proc=55
>2014-06-12 07:56:01.624+00006265: debug : virNetMessageNew:45 : msg=0x79ec50 tracked=1
>2014-06-12 07:56:01.624+00006265: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x79ec50 tx=(nil)
>2014-06-12 07:56:01.624+00006265: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-06-12 07:56:01.624+00006265: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=11 events=1
>2014-06-12 07:56:01.624+00006265: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -140986272
>2014-06-12 07:56:01.624+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 07:56:01.624+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 07:56:01.624+00006265: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-06-12 07:56:01.624+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 07:56:01.624+00006270: debug : remoteDispatchDomainSaveHelper:4607 : server=0x799ff0 client=0x79eb00 msg=0x79f640 rerr=0x7fffef2f4b80 args=0x7fffe413abe0 ret=0x7fffe413b490
>2014-06-12 07:56:01.624+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 07:56:01.624+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=9 e=1 d=0
>2014-06-12 07:56:01.624+00006270: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7fffe413aba0 classname=virDomain
>2014-06-12 07:56:01.624+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=11 e=1 d=0
>2014-06-12 07:56:01.624+00006270: debug : virObjectRef:168 : OBJECT_REF: obj=0x7fffdc007250
>2014-06-12 07:56:01.624+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=14 e=1 d=0
>2014-06-12 07:56:01.624+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=15 e=1 d=0
>2014-06-12 07:56:01.624+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=21 e=1 d=0
>2014-06-12 07:56:01.624+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=22 e=1 d=0
>2014-06-12 07:56:01.624+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2014-06-12 07:56:01.624+00006270: debug : virDomainSave:2563 : dom=0x7fffe413aba0, (VM: name=rhel6, uuid=8a89d1b6-bfb6-ffa9-9664-4d8808d1a279), to=/tmp/rh6.save
>2014-06-12 07:56:01.624+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=10, f=26 e=25 d=0
>2014-06-12 07:56:01.624+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=11, f=19 e=1 d=0
>2014-06-12 07:56:01.624+00006265: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-06-12 07:56:01.624+00006265: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1402559766624
>2014-06-12 07:56:01.624+00006265: debug : virEventPollCalculateTimeout:346 : Timeout at 1402559766624 due in 5000 ms
>2014-06-12 07:56:01.624+00006270: debug : qemuProcessAutoDestroyActive:4915 : vm=rhel6
>2014-06-12 07:56:01.624+00006265: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=5000
>2014-06-12 07:56:01.624+00006270: debug : qemuDriverCloseCallbackGet:724 : vm=rhel6, uuid=8a89d1b6-bfb6-ffa9-9664-4d8808d1a279, conn=(nil)
>2014-06-12 07:56:01.624+00006270: debug : qemuDriverCloseCallbackGet:730 : cb=(nil)
>2014-06-12 07:56:01.624+00006270: debug : virObjectRef:168 : OBJECT_REF: obj=0x7fffe41cfbc0
>2014-06-12 07:56:01.624+00006270: debug : qemuDomainObjBeginJobInternal:852 : Starting async job: save
>2014-06-12 07:56:01.652+00006270: debug : virFileClose:72 : Closed fd 24
>2014-06-12 07:56:01.653+00006270: debug : virObjectRef:168 : OBJECT_REF: obj=0x7fffe41cfbc0
>2014-06-12 07:56:01.653+00006270: debug : qemuDomainObjBeginJobInternal:847 : Starting job: async nested (async=save)
>2014-06-12 07:56:01.653+00006270: debug : virObjectRef:168 : OBJECT_REF: obj=0x7fffdc007e20
>2014-06-12 07:56:01.653+00006270: debug : qemuMonitorStopCPUs:1237 : mon=0x7fffdc007e20
>2014-06-12 07:56:01.653+00006270: debug : virJSONValueToString:1099 : object=0x7fffe413b5e0
>2014-06-12 07:56:01.653+00006270: debug : virJSONValueToStringOne:1030 : object=0x7fffe413b5e0 type=0 gen=0x7fffe413ae60
>2014-06-12 07:56:01.653+00006270: debug : virJSONValueToStringOne:1030 : object=0x7fffe413a1f0 type=2 gen=0x7fffe413ae60
>2014-06-12 07:56:01.653+00006270: debug : virJSONValueToStringOne:1030 : object=0x7fffe413aca0 type=2 gen=0x7fffe413ae60
>2014-06-12 07:56:01.653+00006270: debug : virJSONValueToString:1133 : result={"execute":"stop","id":"libvirt-8"}
>2014-06-12 07:56:01.653+00006270: debug : qemuMonitorJSONCommandWithFd:269 : Send command '{"execute":"stop","id":"libvirt-8"}' for write with FD -1
>2014-06-12 07:56:01.653+00006270: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=15
>2014-06-12 07:56:01.653+00006270: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-06-12 07:56:01.653+00006270: debug : qemuMonitorSend:911 : QEMU_MONITOR_SEND_MSG: mon=0x7fffdc007e20 msg={"execute":"stop","id":"libvirt-8"}
> fd=-1
>2014-06-12 07:56:01.653+00006265: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-06-12 07:56:01.653+00006265: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-06-12 07:56:01.653+00006265: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-06-12 07:56:01.653+00006265: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-06-12 07:56:01.653+00006265: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-06-12 07:56:01.653+00006265: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-06-12 07:56:01.653+00006265: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-06-12 07:56:01.653+00006265: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-06-12 07:56:01.653+00006265: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-06-12 07:56:01.653+00006265: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-06-12 07:56:01.653+00006265: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-06-12 07:56:01.653+00006265: debug : virEventPollDispatchHandles:469 : i=7 w=10
>2014-06-12 07:56:01.653+00006265: debug : virEventPollDispatchHandles:469 : i=8 w=11
>2014-06-12 07:56:01.653+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 07:56:01.653+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 07:56:01.653+00006265: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-06-12 07:56:01.653+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 07:56:01.653+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 07:56:01.653+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=9 e=1 d=0
>2014-06-12 07:56:01.653+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=11 e=1 d=0
>2014-06-12 07:56:01.653+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=14 e=1 d=0
>2014-06-12 07:56:01.653+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=15 e=1 d=0
>2014-06-12 07:56:01.653+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=21 e=1 d=0
>2014-06-12 07:56:01.653+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=22 e=1 d=0
>2014-06-12 07:56:01.653+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2014-06-12 07:56:01.653+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=10, f=26 e=29 d=0
>2014-06-12 07:56:01.653+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=11, f=19 e=1 d=0
>2014-06-12 07:56:01.653+00006265: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-06-12 07:56:01.653+00006265: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1402559766624
>2014-06-12 07:56:01.653+00006265: debug : virEventPollCalculateTimeout:346 : Timeout at 1402559766624 due in 4971 ms
>2014-06-12 07:56:01.653+00006265: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=4971
>2014-06-12 07:56:01.653+00006265: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-06-12 07:56:01.653+00006265: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-06-12 07:56:01.653+00006265: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-06-12 07:56:01.653+00006265: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-06-12 07:56:01.653+00006265: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-06-12 07:56:01.653+00006265: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-06-12 07:56:01.653+00006265: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-06-12 07:56:01.653+00006265: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-06-12 07:56:01.653+00006265: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-06-12 07:56:01.653+00006265: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-06-12 07:56:01.653+00006265: debug : virEventPollDispatchHandles:469 : i=7 w=10
>2014-06-12 07:56:01.653+00006265: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=10 events=2
>2014-06-12 07:56:01.653+00006265: debug : virObjectRef:168 : OBJECT_REF: obj=0x7fffdc007e20
>2014-06-12 07:56:01.653+00006265: debug : qemuMonitorIOWrite:463 : QEMU_MONITOR_IO_WRITE: mon=0x7fffdc007e20 buf={"execute":"stop","id":"libvirt-8"}
> len=37 ret=37 errno=11
>2014-06-12 07:56:01.653+00006265: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=13
>2014-06-12 07:56:01.653+00006265: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -140986272
>2014-06-12 07:56:01.653+00006265: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fffdc007e20
>2014-06-12 07:56:01.653+00006265: debug : virEventPollDispatchHandles:469 : i=8 w=11
>2014-06-12 07:56:01.653+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 07:56:01.653+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 07:56:01.653+00006265: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-06-12 07:56:01.653+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 07:56:01.653+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 07:56:01.653+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=9 e=1 d=0
>2014-06-12 07:56:01.653+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=11 e=1 d=0
>2014-06-12 07:56:01.653+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=14 e=1 d=0
>2014-06-12 07:56:01.653+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=15 e=1 d=0
>2014-06-12 07:56:01.653+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=21 e=1 d=0
>2014-06-12 07:56:01.653+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=22 e=1 d=0
>2014-06-12 07:56:01.653+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2014-06-12 07:56:01.653+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=10, f=26 e=25 d=0
>2014-06-12 07:56:01.653+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=11, f=19 e=1 d=0
>2014-06-12 07:56:01.653+00006265: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-06-12 07:56:01.653+00006265: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1402559766624
>2014-06-12 07:56:01.653+00006265: debug : virEventPollCalculateTimeout:346 : Timeout at 1402559766624 due in 4971 ms
>2014-06-12 07:56:01.653+00006265: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=4971
>2014-06-12 07:56:01.654+00006265: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-06-12 07:56:01.654+00006265: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-06-12 07:56:01.654+00006265: debug : virEventPollDispatchHandles:455 : Dispatch 9
>2014-06-12 07:56:01.654+00006265: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-06-12 07:56:01.654+00006265: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-06-12 07:56:01.654+00006265: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-06-12 07:56:01.654+00006265: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-06-12 07:56:01.654+00006265: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-06-12 07:56:01.654+00006265: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-06-12 07:56:01.654+00006265: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-06-12 07:56:01.654+00006265: debug : virEventPollDispatchHandles:469 : i=7 w=10
>2014-06-12 07:56:01.654+00006265: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=10 events=1
>2014-06-12 07:56:01.654+00006265: debug : virObjectRef:168 : OBJECT_REF: obj=0x7fffdc007e20
>2014-06-12 07:56:01.654+00006265: debug : qemuMonitorIOProcess:355 : QEMU_MONITOR_IO_PROCESS: mon=0x7fffdc007e20 buf={"timestamp": {"seconds": 1402559761, "microseconds": 654166}, "event": "STOP"}
>{"return": {}, "id": "libvirt-8"}
> len=116
>2014-06-12 07:56:01.654+00006265: debug : qemuMonitorJSONIOProcessLine:156 : Line [{"timestamp": {"seconds": 1402559761, "microseconds": 654166}, "event": "STOP"}]
>2014-06-12 07:56:01.654+00006265: debug : virJSONValueFromString:975 : string={"timestamp": {"seconds": 1402559761, "microseconds": 654166}, "event": "STOP"}
>2014-06-12 07:56:01.654+00006265: debug : virJSONParserHandleStartMap:852 : parser=0x7fffffffd740
>2014-06-12 07:56:01.654+00006265: debug : virJSONParserHandleMapKey:833 : parser=0x7fffffffd740 key=0x796672
>2014-06-12 07:56:01.654+00006265: debug : virJSONParserHandleStartMap:852 : parser=0x7fffffffd740
>2014-06-12 07:56:01.654+00006265: debug : virJSONParserHandleMapKey:833 : parser=0x7fffffffd740 key=0x796680
>2014-06-12 07:56:01.654+00006265: debug : virJSONParserHandleNumber:792 : parser=0x7fffffffd740 str=(null)
>2014-06-12 07:56:01.654+00006265: debug : virJSONParserHandleMapKey:833 : parser=0x7fffffffd740 key=0x796697
>2014-06-12 07:56:01.654+00006265: debug : virJSONParserHandleNumber:792 : parser=0x7fffffffd740 str=(null)
>2014-06-12 07:56:01.654+00006265: debug : virJSONParserHandleEndMap:881 : parser=0x7fffffffd740
>2014-06-12 07:56:01.654+00006265: debug : virJSONParserHandleMapKey:833 : parser=0x7fffffffd740 key=0x7966b0
>2014-06-12 07:56:01.654+00006265: debug : virJSONParserHandleString:813 : parser=0x7fffffffd740 str=0x7966b9
>2014-06-12 07:56:01.654+00006265: debug : virJSONParserHandleEndMap:881 : parser=0x7fffffffd740
>2014-06-12 07:56:01.654+00006265: debug : virJSONValueFromString:1019 : result=0x776be0
>2014-06-12 07:56:01.654+00006265: debug : qemuMonitorJSONIOProcessLine:171 : QEMU_MONITOR_RECV_EVENT: mon=0x7fffdc007e20 event={"timestamp": {"seconds": 1402559761, "microseconds": 654166}, "event": "STOP"}
>2014-06-12 07:56:01.654+00006265: debug : qemuMonitorJSONIOProcessEvent:128 : mon=0x7fffdc007e20 obj=0x776be0
>2014-06-12 07:56:01.654+00006265: debug : qemuMonitorJSONIOProcessEvent:142 : handle STOP handler=0x4b5c90 data=(nil)
>2014-06-12 07:56:01.654+00006265: debug : qemuMonitorEmitStop:1025 : mon=0x7fffdc007e20
>2014-06-12 07:56:01.654+00006265: debug : virObjectRef:168 : OBJECT_REF: obj=0x7fffdc007e20
>2014-06-12 07:56:01.654+00006265: debug : qemuProcessHandleStop:729 : Transitioned guest rhel6 to paused state
>2014-06-12 07:56:01.654+00006265: debug : virDomainLockProcessPause:194 : plugin=0x7fffe40e9770 dom=0x7fffe41cfbc0 state=0x7fffe41d23d8
>2014-06-12 07:56:01.654+00006265: debug : virDomainLockManagerNew:128 : plugin=0x7fffe40e9770 dom=0x7fffe41cfbc0 withResources=1
>2014-06-12 07:56:01.654+00006265: debug : virLockManagerPluginGetDriver:283 : plugin=0x7fffe40e9770
>2014-06-12 07:56:01.654+00006265: debug : virLockManagerNew:307 : driver=0x7ffff7de7460 type=0 nparams=5 params=0x7fffffffd600 flags=0
>2014-06-12 07:56:01.654+00006265: debug : virLockManagerLogParams:107 :   key=uuid type=uuid value=8a89d1b6-bfb6-ffa9-9664-4d8808d1a279
>2014-06-12 07:56:01.654+00006265: debug : virLockManagerLogParams:100 :   key=name type=string value=rhel6
>2014-06-12 07:56:01.654+00006265: debug : virLockManagerLogParams:88 :   key=id type=uint value=1
>2014-06-12 07:56:01.654+00006265: debug : virLockManagerLogParams:88 :   key=pid type=uint value=6412
>2014-06-12 07:56:01.654+00006265: debug : virLockManagerLogParams:103 :   key=uri type=cstring value=(null)
>2014-06-12 07:56:01.654+00006265: debug : virDomainLockManagerNew:140 : Adding leases
>2014-06-12 07:56:01.654+00006265: debug : virDomainLockManagerNew:145 : Adding disks
>2014-06-12 07:56:01.654+00006265: debug : virDomainLockManagerAddDisk:86 : Add disk /var/lib/libvirt/images/rhel65.img
>2014-06-12 07:56:01.654+00006265: debug : virLockManagerAddResource:336 : lock=0x79f770 type=0 name=/var/lib/libvirt/images/rhel65.img nparams=0 params=(nil) flags=0
>2014-06-12 07:56:01.654+00006265: debug : virLockManagerRelease:369 : lock=0x79f770 state=0x7fffe41d23d8 flags=0
>2014-06-12 07:56:01.654+00006265: debug : virLockManagerFree:391 : lock=0x79f770
>2014-06-12 07:56:01.654+00006265: debug : qemuProcessHandleStop:739 : Preserving lock state '(null)'
>2014-06-12 07:56:01.694+00006265: debug : virFileClose:72 : Closed fd 24
>2014-06-12 07:56:01.695+00006265: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fffdc007e20
>2014-06-12 07:56:01.695+00006265: debug : qemuMonitorJSONIOProcessLine:156 : Line [{"return": {}, "id": "libvirt-8"}]
>2014-06-12 07:56:01.695+00006265: debug : virJSONValueFromString:975 : string={"return": {}, "id": "libvirt-8"}
>2014-06-12 07:56:01.695+00006265: debug : virJSONParserHandleStartMap:852 : parser=0x7fffffffd740
>2014-06-12 07:56:01.695+00006265: debug : virJSONParserHandleMapKey:833 : parser=0x7fffffffd740 key=0x7968b2
>2014-06-12 07:56:01.695+00006265: debug : virJSONParserHandleStartMap:852 : parser=0x7fffffffd740
>2014-06-12 07:56:01.695+00006265: debug : virJSONParserHandleEndMap:881 : parser=0x7fffffffd740
>2014-06-12 07:56:01.695+00006265: debug : virJSONParserHandleMapKey:833 : parser=0x7fffffffd740 key=0x7968c0
>2014-06-12 07:56:01.695+00006265: debug : virJSONParserHandleString:813 : parser=0x7fffffffd740 str=0x7968c6
>2014-06-12 07:56:01.695+00006265: debug : virJSONParserHandleEndMap:881 : parser=0x7fffffffd740
>2014-06-12 07:56:01.695+00006265: debug : virJSONValueFromString:1019 : result=0x776f40
>2014-06-12 07:56:01.695+00006265: debug : qemuMonitorJSONIOProcessLine:176 : QEMU_MONITOR_RECV_REPLY: mon=0x7fffdc007e20 reply={"return": {}, "id": "libvirt-8"}
>2014-06-12 07:56:01.695+00006265: debug : qemuMonitorJSONIOProcess:227 : Total used 116 bytes out of 116 available in buffer
>2014-06-12 07:56:01.695+00006265: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=13
>2014-06-12 07:56:01.695+00006265: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -140986272
>2014-06-12 07:56:01.695+00006265: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fffdc007e20
>2014-06-12 07:56:01.695+00006265: debug : virEventPollDispatchHandles:469 : i=8 w=11
>2014-06-12 07:56:01.695+00006270: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=10 events=13
>2014-06-12 07:56:01.695+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 07:56:01.695+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 07:56:01.695+00006265: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-06-12 07:56:01.695+00006270: debug : virEventPollInterruptLocked:697 : Skip interrupt, 0 -140986272
>2014-06-12 07:56:01.695+00006270: debug : qemuMonitorJSONCommandWithFd:274 : Receive command reply ret=0 rxObject=0x776f40
>2014-06-12 07:56:01.695+00006265: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-06-12 07:56:01.695+00006270: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fffdc007e20
>2014-06-12 07:56:01.695+00006265: debug : virEventPollCleanupHandles:549 : Cleanup 9
>2014-06-12 07:56:01.695+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=9 e=1 d=0
>2014-06-12 07:56:01.695+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=11 e=1 d=0
>2014-06-12 07:56:01.695+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=14 e=1 d=0
>2014-06-12 07:56:01.695+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=15 e=1 d=0
>2014-06-12 07:56:01.695+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=21 e=1 d=0
>2014-06-12 07:56:01.695+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=22 e=1 d=0
>2014-06-12 07:56:01.695+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2014-06-12 07:56:01.695+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=10, f=26 e=25 d=0
>2014-06-12 07:56:01.695+00006265: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=11, f=19 e=1 d=0
>2014-06-12 07:56:01.695+00006265: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-06-12 07:56:01.695+00006265: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1402559766624
>2014-06-12 07:56:01.695+00006265: debug : virEventPollCalculateTimeout:346 : Timeout at 1402559766624 due in 4929 ms
>2014-06-12 07:56:01.695+00006265: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=9 timeout=4929
>2014-06-12 07:56:01.720+00006270: debug : virFileClose:72 : Closed fd 24
>2014-06-12 07:56:01.720+00006270: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fffe41cfbc0
>2014-06-12 07:56:01.720+00006270: debug : virDomainLockProcessPause:194 : plugin=0x7fffe40e9770 dom=0x7fffe41cfbc0 state=0x7fffe41d23d8
>2014-06-12 07:56:01.720+00006270: debug : virDomainLockManagerNew:128 : plugin=0x7fffe40e9770 dom=0x7fffe41cfbc0 withResources=1
>2014-06-12 07:56:01.720+00006270: debug : virLockManagerPluginGetDriver:283 : plugin=0x7fffe40e9770
>2014-06-12 07:56:01.720+00006270: debug : virLockManagerNew:307 : driver=0x7ffff7de7460 type=0 nparams=5 params=0x7fffef2f4770 flags=0
>2014-06-12 07:56:01.720+00006270: debug : virLockManagerLogParams:107 :   key=uuid type=uuid value=8a89d1b6-bfb6-ffa9-9664-4d8808d1a279
>2014-06-12 07:56:01.720+00006270: debug : virLockManagerLogParams:100 :   key=name type=string value=rhel6
>2014-06-12 07:56:01.720+00006270: debug : virLockManagerLogParams:88 :   key=id type=uint value=1
>2014-06-12 07:56:01.720+00006270: debug : virLockManagerLogParams:88 :   key=pid type=uint value=6412
>2014-06-12 07:56:01.720+00006270: debug : virLockManagerLogParams:103 :   key=uri type=cstring value=(null)
>2014-06-12 07:56:01.720+00006270: debug : virDomainLockManagerNew:140 : Adding leases
>2014-06-12 07:56:01.720+00006270: debug : virDomainLockManagerNew:145 : Adding disks
>2014-06-12 07:56:01.720+00006270: debug : virDomainLockManagerAddDisk:86 : Add disk /var/lib/libvirt/images/rhel65.img
>2014-06-12 07:56:01.720+00006270: debug : virLockManagerAddResource:336 : lock=0x7fffe40deec0 type=0 name=/var/lib/libvirt/images/rhel65.img nparams=0 params=(nil) flags=0
>2014-06-12 07:56:01.720+00006270: debug : virLockManagerRelease:369 : lock=0x7fffe40deec0 state=0x7fffe41d23d8 flags=0
>2014-06-12 07:56:01.720+00006270: debug : virLockManagerFree:391 : lock=0x7fffe40deec0
>2014-06-12 07:56:01.720+00006270: debug : qemuProcessStopCPUs:2976 : Preserving lock state '(null)'
>2014-06-12 07:56:01.720+00006270: debug : qemuDomainDefFormatBuf:1339 : Removing default USB controller from domain 'rhel6' for migration compatibility
>
>
>     ====== end of log =====
>

Actions: View

Attachments on bug 1105954: 908005