Attachment 922456 Details for Bug 1124740 – libvirtd-crash.log

libvirtd-crash.log

libvirtd-crash.log (text/plain), 64.12 KB, created by yanbing du on 2014-07-30 08:25:55 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: yanbing du

Created: 2014-07-30 08:25:55 UTC

Size: 64.12 KB

patch

obsolete

>
>Caught Segmentation violation dumping internal log buffer:
>
>
>    ====== start of log =====
>
>
> : Cleanup 21
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCleanupHandles:549 : Cleanup 17
>2014-07-30 05:14:56.210+000024532: debug : remoteDispatchConnectListAllDomainsHelper:146 : server=0xf5da30 client=0xf62200 msg=0xf632d0 rerr=0x7f6e80fc4b80 args=0x7f6e5c06f3c0 ret=0x7f6e5c070ee0
>2014-07-30 05:14:56.210+000024529: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-30 05:14:56.210+000024532: debug : virConnectListAllDomains:8321 : conn=0x7f6e74136ba0, domains=0x7f6e80fc4af8, flags=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCleanupTimeouts:501 : Cleanup 21
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCleanupHandles:549 : Cleanup 17
>2014-07-30 05:14:56.210+000024532: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f6e5c206910 classname=virDomain
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-30 05:14:56.210+000024532: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f6e74136ba0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-30 05:14:56.210+000024532: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f6e5c2930a0 classname=virDomain
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-30 05:14:56.210+000024532: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f6e74136ba0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-30 05:14:56.210+000024532: debug : virDomainFree:2281 : dom=0x7f6e5c206910, (VM: name=virt-tests-vm1, uuid=7473561f-a9fe-63db-80fb-c9d88e697efd)
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=18 e=1 d=0
>2014-07-30 05:14:56.210+000024532: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f6e5c206910
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=1 d=0
>2014-07-30 05:14:56.210+000024532: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f6e5c206910
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=24 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=27 e=1 d=0
>2014-07-30 05:14:56.210+000024532: debug : virDomainDispose:264 : release domain 0x7f6e5c206910 virt-tests-vm1 7473561f-a9fe-63db-80fb-c9d88e697efd
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=30 e=1 d=0
>2014-07-30 05:14:56.210+000024532: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f6e74136ba0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=13, f=33 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=13 w=14, f=36 e=1 d=0
>2014-07-30 05:14:56.210+000024532: debug : virDomainFree:2281 : dom=0x7f6e5c2930a0, (VM: name=test, uuid=fb5585c2-4230-79f6-bfc8-94d291ba75e4)
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=14 w=15, f=39 e=1 d=0
>2014-07-30 05:14:56.210+000024532: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f6e5c2930a0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=15 w=16, f=42 e=1 d=0
>2014-07-30 05:14:56.210+000024532: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f6e5c2930a0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=16 w=17, f=45 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 21 timers
>2014-07-30 05:14:56.210+000024532: debug : virDomainDispose:264 : release domain 0x7f6e5c2930a0 test fb5585c2-4230-79f6-bfc8-94d291ba75e4
>2014-07-30 05:14:56.210+000024532: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f6e74136ba0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297472
>2014-07-30 05:14:56.210+000024532: debug : virNetMessageEncodePayload:358 : Encode length as 104
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.210+000024532: debug : virNetServerClientSendMessageLocked:1122 : msg=0xf632d0 proc=273 len=104 offset=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697298077
>2014-07-30 05:14:56.210+000024532: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0xf62200 len=104 prog=536903814 vers=1 proc=273 type=1 status=0 serial=42
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697296926
>2014-07-30 05:14:56.210+000024532: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0xf66ae0 tx=0xf632d0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697300934
>2014-07-30 05:14:56.210+000024532: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697300678
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297698
>2014-07-30 05:14:56.210+000024532: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=17 events=3
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697301210
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:346 : Timeout at 1406697296926 due in 716 ms
>2014-07-30 05:14:56.210+000024529: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=17 timeout=716
>2014-07-30 05:14:56.210+000024532: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-07-30 05:14:56.210+000024532: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0xf66480
>2014-07-30 05:14:56.210+000024529: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-30 05:14:56.210+000024532: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0xf62200
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchTimeouts:410 : Dispatch 21
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:455 : Dispatch 17
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=12 w=13
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=13 w=14
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=14 w=15
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=15 w=16
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=16 w=17
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCleanupTimeouts:501 : Cleanup 21
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCleanupHandles:549 : Cleanup 17
>2014-07-30 05:14:56.210+000024529: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCleanupTimeouts:501 : Cleanup 21
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCleanupHandles:549 : Cleanup 17
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=18 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=24 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=27 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=30 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=13, f=33 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=13 w=14, f=36 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=14 w=15, f=39 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=15 w=16, f=42 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=16 w=17, f=45 e=5 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 21 timers
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297472
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697298077
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697296926
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697300934
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697300678
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297698
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697301210
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:346 : Timeout at 1406697296926 due in 716 ms
>2014-07-30 05:14:56.210+000024529: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=17 timeout=716
>2014-07-30 05:14:56.210+000024529: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchTimeouts:410 : Dispatch 21
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:455 : Dispatch 17
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=12 w=13
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=13 w=14
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=14 w=15
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=15 w=16
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:469 : i=16 w=17
>2014-07-30 05:14:56.210+000024529: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=17 events=2
>2014-07-30 05:14:56.210+000024529: debug : virNetMessageFree:73 : msg=0xf632d0 nfds=0 cb=(nil)
>2014-07-30 05:14:56.210+000024529: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0xf66ae0 tx=(nil)
>2014-07-30 05:14:56.210+000024529: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-30 05:14:56.210+000024529: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=17 events=1
>2014-07-30 05:14:56.210+000024529: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2010802080
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCleanupTimeouts:501 : Cleanup 21
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCleanupHandles:549 : Cleanup 17
>2014-07-30 05:14:56.210+000024529: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCleanupTimeouts:501 : Cleanup 21
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCleanupHandles:549 : Cleanup 17
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=18 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=24 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=27 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=30 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=13, f=33 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=13 w=14, f=36 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=14 w=15, f=39 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=15 w=16, f=42 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=16 w=17, f=45 e=1 d=0
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 21 timers
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297472
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697298077
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697296926
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697300934
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697300678
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297698
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697301210
>2014-07-30 05:14:56.210+000024529: debug : virEventPollCalculateTimeout:346 : Timeout at 1406697296926 due in 716 ms
>2014-07-30 05:14:56.210+000024529: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=17 timeout=716
>2014-07-30 05:14:56.211+000024529: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchTimeouts:410 : Dispatch 21
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:455 : Dispatch 17
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=12 w=13
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=13 w=14
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=14 w=15
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=15 w=16
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=16 w=17
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=17 events=1
>2014-07-30 05:14:56.211+000024529: debug : virNetMessageDecodeLength:152 : Got length, now need 36 total (32 more)
>2014-07-30 05:14:56.211+000024529: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0xf66ae0 tx=(nil)
>2014-07-30 05:14:56.211+000024529: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-30 05:14:56.211+000024529: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=17 events=1
>2014-07-30 05:14:56.211+000024529: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2010802080
>2014-07-30 05:14:56.211+000024529: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0xf62200 len=36 prog=536903814 vers=1 proc=273 type=0 status=0 serial=43
>2014-07-30 05:14:56.211+000024529: debug : virKeepAliveCheckMessage:384 : ka=0xf62800, client=0xf62200, msg=0xf66ae0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=21 frequency=5000
>2014-07-30 05:14:56.211+000024529: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2010802080
>2014-07-30 05:14:56.211+000024529: debug : virObjectRef:168 : OBJECT_REF: obj=0xf62200
>2014-07-30 05:14:56.211+000024529: debug : virNetServerDispatchNewMessage:218 : server=0xf5da30 client=0xf62200 message=0xf66ae0
>2014-07-30 05:14:56.211+000024529: debug : virObjectRef:168 : OBJECT_REF: obj=0xf66480
>2014-07-30 05:14:56.211+000024534: debug : virNetServerHandleJob:189 : server=0xf5da30 client=0xf62200 message=0xf66ae0 prog=0xf66480
>2014-07-30 05:14:56.211+000024534: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=43 proc=273
>2014-07-30 05:14:56.211+000024529: debug : virNetMessageNew:45 : msg=0xf654d0 tracked=1
>2014-07-30 05:14:56.211+000024529: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0xf654d0 tx=(nil)
>2014-07-30 05:14:56.211+000024529: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-30 05:14:56.211+000024529: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=17 events=1
>2014-07-30 05:14:56.211+000024529: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2010802080
>2014-07-30 05:14:56.211+000024534: debug : remoteDispatchConnectListAllDomainsHelper:146 : server=0xf5da30 client=0xf62200 msg=0xf66ae0 rerr=0x7f6e7fbc2b80 args=0x7f6e60275a20 ret=0x7f6e602759b0
>2014-07-30 05:14:56.211+000024534: debug : virConnectListAllDomains:8321 : conn=0x7f6e74136ba0, domains=0x7f6e7fbc2af8, flags=0
>2014-07-30 05:14:56.211+000024534: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f6e60275ab0 classname=virDomain
>2014-07-30 05:14:56.211+000024534: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f6e74136ba0
>2014-07-30 05:14:56.211+000024534: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f6e60275b20 classname=virDomain
>2014-07-30 05:14:56.211+000024534: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f6e74136ba0
>2014-07-30 05:14:56.211+000024534: debug : virDomainFree:2281 : dom=0x7f6e60275ab0, (VM: name=virt-tests-vm1, uuid=7473561f-a9fe-63db-80fb-c9d88e697efd)
>2014-07-30 05:14:56.211+000024534: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f6e60275ab0
>2014-07-30 05:14:56.211+000024534: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f6e60275ab0
>2014-07-30 05:14:56.211+000024534: debug : virDomainDispose:264 : release domain 0x7f6e60275ab0 virt-tests-vm1 7473561f-a9fe-63db-80fb-c9d88e697efd
>2014-07-30 05:14:56.211+000024534: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f6e74136ba0
>2014-07-30 05:14:56.211+000024534: debug : virDomainFree:2281 : dom=0x7f6e60275b20, (VM: name=test, uuid=fb5585c2-4230-79f6-bfc8-94d291ba75e4)
>2014-07-30 05:14:56.211+000024534: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f6e60275b20
>2014-07-30 05:14:56.211+000024534: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f6e60275b20
>2014-07-30 05:14:56.211+000024534: debug : virDomainDispose:264 : release domain 0x7f6e60275b20 test fb5585c2-4230-79f6-bfc8-94d291ba75e4
>2014-07-30 05:14:56.211+000024534: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f6e74136ba0
>2014-07-30 05:14:56.211+000024534: debug : virNetMessageEncodePayload:358 : Encode length as 104
>2014-07-30 05:14:56.211+000024534: debug : virNetServerClientSendMessageLocked:1122 : msg=0xf66ae0 proc=273 len=104 offset=0
>2014-07-30 05:14:56.211+000024534: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0xf62200 len=104 prog=536903814 vers=1 proc=273 type=1 status=0 serial=43
>2014-07-30 05:14:56.211+000024534: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0xf654d0 tx=0xf66ae0
>2014-07-30 05:14:56.211+000024534: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-07-30 05:14:56.211+000024534: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=17 events=3
>2014-07-30 05:14:56.211+000024534: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-07-30 05:14:56.211+000024534: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0xf66480
>2014-07-30 05:14:56.211+000024534: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0xf62200
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCleanupTimeouts:501 : Cleanup 21
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCleanupHandles:549 : Cleanup 17
>2014-07-30 05:14:56.211+000024529: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCleanupTimeouts:501 : Cleanup 21
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCleanupHandles:549 : Cleanup 17
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=18 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=24 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=27 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=30 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=13, f=33 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=13 w=14, f=36 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=14 w=15, f=39 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=15 w=16, f=42 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=16 w=17, f=45 e=5 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 21 timers
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297472
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697298077
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697296926
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697300934
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697300678
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297698
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697301211
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCalculateTimeout:346 : Timeout at 1406697296926 due in 715 ms
>2014-07-30 05:14:56.211+000024529: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=17 timeout=715
>2014-07-30 05:14:56.211+000024529: debug : virEventPollRunOnce:625 : Poll got 2 event(s)
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchTimeouts:410 : Dispatch 21
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:455 : Dispatch 17
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=12 w=13
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=13 w=14
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=14 w=15
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=15 w=16
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:469 : i=16 w=17
>2014-07-30 05:14:56.211+000024529: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=17 events=2
>2014-07-30 05:14:56.211+000024529: debug : virNetMessageFree:73 : msg=0xf66ae0 nfds=0 cb=(nil)
>2014-07-30 05:14:56.211+000024529: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0xf654d0 tx=(nil)
>2014-07-30 05:14:56.211+000024529: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-30 05:14:56.211+000024529: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=17 events=1
>2014-07-30 05:14:56.211+000024529: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2010802080
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCleanupTimeouts:501 : Cleanup 21
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCleanupHandles:549 : Cleanup 17
>2014-07-30 05:14:56.211+000024529: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCleanupTimeouts:501 : Cleanup 21
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCleanupHandles:549 : Cleanup 17
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=18 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=24 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=27 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=30 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=13, f=33 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=13 w=14, f=36 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=14 w=15, f=39 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=15 w=16, f=42 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=16 w=17, f=45 e=1 d=0
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 21 timers
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297472
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697298077
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697296926
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697300934
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697300678
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297698
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697301211
>2014-07-30 05:14:56.211+000024529: debug : virEventPollCalculateTimeout:346 : Timeout at 1406697296926 due in 715 ms
>2014-07-30 05:14:56.211+000024529: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=17 timeout=715
>2014-07-30 05:14:56.212+000024529: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchTimeouts:410 : Dispatch 21
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:455 : Dispatch 17
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=12 w=13
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=13 w=14
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=14 w=15
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=15 w=16
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=16 w=17
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=17 events=1
>2014-07-30 05:14:56.212+000024529: debug : virNetMessageDecodeLength:152 : Got length, now need 72 total (68 more)
>2014-07-30 05:14:56.212+000024529: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0xf654d0 tx=(nil)
>2014-07-30 05:14:56.212+000024529: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-30 05:14:56.212+000024529: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=17 events=1
>2014-07-30 05:14:56.212+000024529: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2010802080
>2014-07-30 05:14:56.212+000024529: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0xf62200 len=72 prog=536903814 vers=1 proc=183 type=0 status=0 serial=44
>2014-07-30 05:14:56.212+000024529: debug : virKeepAliveCheckMessage:384 : ka=0xf62800, client=0xf62200, msg=0xf654d0
>2014-07-30 05:14:56.212+000024529: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=21 frequency=5000
>2014-07-30 05:14:56.212+000024529: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2010802080
>2014-07-30 05:14:56.212+000024529: debug : virObjectRef:168 : OBJECT_REF: obj=0xf62200
>2014-07-30 05:14:56.212+000024529: debug : virNetServerDispatchNewMessage:218 : server=0xf5da30 client=0xf62200 message=0xf654d0
>2014-07-30 05:14:56.212+000024529: debug : virObjectRef:168 : OBJECT_REF: obj=0xf66480
>2014-07-30 05:14:56.212+000024529: debug : virNetMessageNew:45 : msg=0xf632d0 tracked=1
>2014-07-30 05:14:56.212+000024529: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0xf632d0 tx=(nil)
>2014-07-30 05:14:56.212+000024529: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-30 05:14:56.212+000024529: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=17 events=1
>2014-07-30 05:14:56.212+000024529: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2010802080
>2014-07-30 05:14:56.212+000024529: debug : virEventPollCleanupTimeouts:501 : Cleanup 21
>2014-07-30 05:14:56.212+000024529: debug : virEventPollCleanupHandles:549 : Cleanup 17
>2014-07-30 05:14:56.212+000024529: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-30 05:14:56.212+000024529: debug : virEventPollCleanupTimeouts:501 : Cleanup 21
>2014-07-30 05:14:56.212+000024529: debug : virEventPollCleanupHandles:549 : Cleanup 17
>2014-07-30 05:14:56.212+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-30 05:14:56.212+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-30 05:14:56.212+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-30 05:14:56.212+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-30 05:14:56.212+000024531: debug : virNetServerHandleJob:189 : server=0xf5da30 client=0xf62200 message=0xf654d0 prog=0xf66480
>2014-07-30 05:14:56.212+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-30 05:14:56.212+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2014-07-30 05:14:56.212+000024531: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=44 proc=183
>2014-07-30 05:14:56.212+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-30 05:14:56.212+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=18 e=1 d=0
>2014-07-30 05:14:56.212+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=1 d=0
>2014-07-30 05:14:56.212+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=24 e=1 d=0
>2014-07-30 05:14:56.212+000024531: debug : remoteDispatchDomainHasManagedSaveImageHelper:2671 : server=0xf5da30 client=0xf62200 msg=0xf654d0 rerr=0x7f6e819c5b80 args=0x7f6e70185680 ret=0x7f6e70185620
>2014-07-30 05:14:56.212+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=27 e=1 d=0
>2014-07-30 05:14:56.212+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=30 e=1 d=0
>2014-07-30 05:14:56.212+000024531: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f6e70185640 classname=virDomain
>2014-07-30 05:14:56.212+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=13, f=33 e=1 d=0
>2014-07-30 05:14:56.212+000024531: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f6e74136ba0
>2014-07-30 05:14:56.212+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=13 w=14, f=36 e=1 d=0
>2014-07-30 05:14:56.212+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=14 w=15, f=39 e=1 d=0
>2014-07-30 05:14:56.212+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=15 w=16, f=42 e=1 d=0
>2014-07-30 05:14:56.212+000024531: debug : virDomainHasManagedSaveImage:17708 : dom=0x7f6e70185640, (VM: name=virt-tests-vm1, uuid=7473561f-a9fe-63db-80fb-c9d88e697efd), flags=0
>2014-07-30 05:14:56.212+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=16 w=17, f=45 e=1 d=0
>2014-07-30 05:14:56.212+000024529: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 21 timers
>2014-07-30 05:14:56.212+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297472
>2014-07-30 05:14:56.212+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.212+000024531: debug : virDomainFree:2281 : dom=0x7f6e70185640, (VM: name=virt-tests-vm1, uuid=7473561f-a9fe-63db-80fb-c9d88e697efd)
>2014-07-30 05:14:56.212+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.212+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.212+000024531: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f6e70185640
>2014-07-30 05:14:56.212+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697298077
>2014-07-30 05:14:56.212+000024531: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f6e70185640
>2014-07-30 05:14:56.212+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697296926
>2014-07-30 05:14:56.212+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697300934
>2014-07-30 05:14:56.212+000024531: debug : virDomainDispose:264 : release domain 0x7f6e70185640 virt-tests-vm1 7473561f-a9fe-63db-80fb-c9d88e697efd
>2014-07-30 05:14:56.212+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697300678
>2014-07-30 05:14:56.212+000024531: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f6e74136ba0
>2014-07-30 05:14:56.212+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297698
>2014-07-30 05:14:56.212+000024531: debug : virNetMessageEncodePayload:358 : Encode length as 32
>2014-07-30 05:14:56.212+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697301212
>2014-07-30 05:14:56.212+000024529: debug : virEventPollCalculateTimeout:346 : Timeout at 1406697296926 due in 714 ms
>2014-07-30 05:14:56.212+000024531: debug : virNetServerClientSendMessageLocked:1122 : msg=0xf654d0 proc=183 len=32 offset=0
>2014-07-30 05:14:56.212+000024529: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=17 timeout=714
>2014-07-30 05:14:56.212+000024531: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0xf62200 len=32 prog=536903814 vers=1 proc=183 type=1 status=0 serial=44
>2014-07-30 05:14:56.212+000024531: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0xf632d0 tx=0xf654d0
>2014-07-30 05:14:56.212+000024531: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-07-30 05:14:56.212+000024531: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=17 events=3
>2014-07-30 05:14:56.212+000024531: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-07-30 05:14:56.212+000024531: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0xf66480
>2014-07-30 05:14:56.212+000024531: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0xf62200
>2014-07-30 05:14:56.212+000024529: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchTimeouts:410 : Dispatch 21
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:455 : Dispatch 17
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=12 w=13
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=13 w=14
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=14 w=15
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=15 w=16
>2014-07-30 05:14:56.212+000024529: debug : virEventPollDispatchHandles:469 : i=16 w=17
>2014-07-30 05:14:56.212+000024529: debug : virEventPollCleanupTimeouts:501 : Cleanup 21
>2014-07-30 05:14:56.212+000024529: debug : virEventPollCleanupHandles:549 : Cleanup 17
>2014-07-30 05:14:56.212+000024529: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-30 05:14:56.212+000024529: debug : virEventPollCleanupTimeouts:501 : Cleanup 21
>2014-07-30 05:14:56.212+000024529: debug : virEventPollCleanupHandles:549 : Cleanup 17
>2014-07-30 05:14:56.212+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-30 05:14:56.212+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-30 05:14:56.212+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-30 05:14:56.212+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-30 05:14:56.212+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-30 05:14:56.212+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=18 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=24 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=27 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=30 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=13, f=33 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=13 w=14, f=36 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=14 w=15, f=39 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=15 w=16, f=42 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=16 w=17, f=45 e=5 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 21 timers
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297472
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697298077
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697296926
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697300934
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697300678
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297698
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697301212
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCalculateTimeout:346 : Timeout at 1406697296926 due in 713 ms
>2014-07-30 05:14:56.213+000024529: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=17 timeout=713
>2014-07-30 05:14:56.213+000024529: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchTimeouts:410 : Dispatch 21
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:455 : Dispatch 17
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=12 w=13
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=13 w=14
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=14 w=15
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=15 w=16
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=16 w=17
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=17 events=2
>2014-07-30 05:14:56.213+000024529: debug : virNetMessageFree:73 : msg=0xf654d0 nfds=0 cb=(nil)
>2014-07-30 05:14:56.213+000024529: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0xf632d0 tx=(nil)
>2014-07-30 05:14:56.213+000024529: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-30 05:14:56.213+000024529: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=17 events=1
>2014-07-30 05:14:56.213+000024529: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2010802080
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCleanupTimeouts:501 : Cleanup 21
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCleanupHandles:549 : Cleanup 17
>2014-07-30 05:14:56.213+000024529: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCleanupTimeouts:501 : Cleanup 21
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCleanupHandles:549 : Cleanup 17
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=18 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=24 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=27 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=30 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=13, f=33 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=13 w=14, f=36 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=14 w=15, f=39 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=15 w=16, f=42 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=16 w=17, f=45 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 21 timers
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297472
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697298077
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697296926
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697300934
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697300678
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297698
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697301212
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCalculateTimeout:346 : Timeout at 1406697296926 due in 713 ms
>2014-07-30 05:14:56.213+000024529: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=17 timeout=713
>2014-07-30 05:14:56.213+000024529: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchTimeouts:410 : Dispatch 21
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:455 : Dispatch 17
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=11 w=12
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=12 w=13
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=13 w=14
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=14 w=15
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=15 w=16
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:469 : i=16 w=17
>2014-07-30 05:14:56.213+000024529: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=17 events=1
>2014-07-30 05:14:56.213+000024529: debug : virNetMessageDecodeLength:152 : Got length, now need 80 total (76 more)
>2014-07-30 05:14:56.213+000024529: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0xf632d0 tx=(nil)
>2014-07-30 05:14:56.213+000024529: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-30 05:14:56.213+000024529: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=17 events=1
>2014-07-30 05:14:56.213+000024529: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2010802080
>2014-07-30 05:14:56.213+000024529: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0xf62200 len=80 prog=536903814 vers=1 proc=265 type=0 status=0 serial=45
>2014-07-30 05:14:56.213+000024529: debug : virKeepAliveCheckMessage:384 : ka=0xf62800, client=0xf62200, msg=0xf632d0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=21 frequency=5000
>2014-07-30 05:14:56.213+000024529: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2010802080
>2014-07-30 05:14:56.213+000024529: debug : virObjectRef:168 : OBJECT_REF: obj=0xf62200
>2014-07-30 05:14:56.213+000024529: debug : virNetServerDispatchNewMessage:218 : server=0xf5da30 client=0xf62200 message=0xf632d0
>2014-07-30 05:14:56.213+000024529: debug : virObjectRef:168 : OBJECT_REF: obj=0xf66480
>2014-07-30 05:14:56.213+000024529: debug : virNetMessageNew:45 : msg=0xf66ae0 tracked=1
>2014-07-30 05:14:56.213+000024529: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0xf66ae0 tx=(nil)
>2014-07-30 05:14:56.213+000024529: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-30 05:14:56.213+000024529: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=17 events=1
>2014-07-30 05:14:56.213+000024529: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -2010802080
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCleanupTimeouts:501 : Cleanup 21
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCleanupHandles:549 : Cleanup 17
>2014-07-30 05:14:56.213+000024529: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCleanupTimeouts:501 : Cleanup 21
>2014-07-30 05:14:56.213+000024529: debug : virEventPollCleanupHandles:549 : Cleanup 17
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-30 05:14:56.213+000024530: debug : virNetServerHandleJob:189 : server=0xf5da30 client=0xf62200 message=0xf632d0 prog=0xf66480
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=15 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-30 05:14:56.213+000024530: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=45 proc=265
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=18 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=21 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=24 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=27 e=1 d=0
>2014-07-30 05:14:56.213+000024530: debug : remoteDispatchDomainGetMetadataHelper:2190 : server=0xf5da30 client=0xf62200 msg=0xf632d0 rerr=0x7f6e823c6b80 args=0x7f6e742275d0 ret=0x7f6e7423c680
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=12, f=30 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=12 w=13, f=33 e=1 d=0
>2014-07-30 05:14:56.213+000024530: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f6e74226af0 classname=virDomain
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=13 w=14, f=36 e=1 d=0
>2014-07-30 05:14:56.213+000024530: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f6e74136ba0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=14 w=15, f=39 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=15 w=16, f=42 e=1 d=0
>2014-07-30 05:14:56.213+000024529: debug : virEventPollMakePollFDs:378 : Prepare n=16 w=17, f=45 e=1 d=0
>2014-07-30 05:14:56.214+000024529: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 21 timers
>2014-07-30 05:14:56.214+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297472
>2014-07-30 05:14:56.214+000024530: debug : virDomainGetMetadata:9591 : dom=0x7f6e74226af0, (VM: name=virt-tests-vm1, uuid=7473561f-a9fe-63db-80fb-c9d88e697efd), type=1, uri='(null)', flags=0
>2014-07-30 05:14:56.214+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.214+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.214+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297473
>2014-07-30 05:14:56.214+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697298077
>2014-07-30 05:14:56.214+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697296926
>2014-07-30 05:14:56.214+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697300934
>2014-07-30 05:14:56.214+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697300678
>2014-07-30 05:14:56.214+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697297698
>2014-07-30 05:14:56.214+000024529: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1406697301213
>2014-07-30 05:14:56.214+000024529: debug : virEventPollCalculateTimeout:346 : Timeout at 1406697296926 due in 712 ms
>2014-07-30 05:14:56.214+000024529: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=17 timeout=712
>
>
>     ====== end of log =====
>

Actions: View

Attachments on bug 1124740: 922456