Attachment 828554 Details for Bug 1025108 – libvirtd log

libvirtd log

libvirtd_debug.log (text/plain), 123.74 KB, created by hongming on 2013-11-25 08:27:55 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: hongming

Created: 2013-11-25 08:27:55 UTC

Size: 123.74 KB

patch

obsolete

>2013-11-25 08:26:02.001+0000: 3913: debug : virEventPollRunOnce:638 : Poll got 1 event(s)
>2013-11-25 08:26:02.001+0000: 3913: debug : virEventPollDispatchTimeouts:423 : Dispatch 1
>2013-11-25 08:26:02.001+0000: 3913: debug : virEventPollDispatchHandles:468 : Dispatch 9
>2013-11-25 08:26:02.001+0000: 3913: debug : virEventPollDispatchHandles:482 : i=0 w=1
>2013-11-25 08:26:02.001+0000: 3913: debug : virEventPollDispatchHandles:482 : i=1 w=2
>2013-11-25 08:26:02.001+0000: 3913: debug : virEventPollDispatchHandles:482 : i=2 w=3
>2013-11-25 08:26:02.001+0000: 3913: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=3 events=1
>2013-11-25 08:26:02.002+0000: 3913: debug : virNetSocketNew:155 : localAddr=0x7fffe44a6ce0 remoteAddr=0x7fffe44a6d70 fd=22 errfd=-1 pid=0
>2013-11-25 08:26:02.002+0000: 3913: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7fefbfeb4fd0 classname=virNetSocket
>2013-11-25 08:26:02.002+0000: 3913: debug : virNetSocketNew:205 : RPC_SOCKET_NEW: sock=0x7fefbfeb4fd0 fd=22 errfd=-1 pid=0 localAddr=127.0.0.1;0, remoteAddr=127.0.0.1;0
>2013-11-25 08:26:02.002+0000: 3913: debug : virNetServerClientNew:414 : sock=0x7fefbfeb4fd0 auth=2 tls=(nil)
>2013-11-25 08:26:02.002+0000: 3913: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7fefbfeb5710 classname=virNetServerClient
>2013-11-25 08:26:02.002+0000: 3913: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeb4fd0
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollAddTimeout:248 : EVENT_POLL_ADD_TIMEOUT: timer=5 frequency=-1 cb=0x7fefbdd0e260 opaque=0x7fefbfeb5710 ff=(nil)
>2013-11-25 08:26:02.002+0000: 3913: debug : virNetMessageNew:44 : msg=0x7fefbfeb5800 tracked=1
>2013-11-25 08:26:02.002+0000: 3913: debug : virNetServerClientNewInternal:384 : RPC_SERVER_CLIENT_NEW: client=0x7fefbfeb5710 sock=0x7fefbfeb4fd0
>2013-11-25 08:26:02.002+0000: 3913: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb5800 tx=(nil)
>2013-11-25 08:26:02.002+0000: 3913: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2013-11-25 08:26:02.002+0000: 3913: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.002+0000: 3913: debug : virNetServerClientRegisterEvent:204 : Registering client event callback 1
>2013-11-25 08:26:02.002+0000: 3913: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeb4fd0
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollAddHandle:136 : EVENT_POLL_ADD_HANDLE: watch=14 fd=22 events=1 cb=0x7fefbdd15f00 opaque=0x7fefbfeb4fd0 ff=0x7fefbdd15eb0
>2013-11-25 08:26:02.002+0000: 3913: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.002+0000: 3913: debug : virKeepAliveNew:196 : client=0x7fefbfeb5710, interval=5, count=5
>2013-11-25 08:26:02.002+0000: 3913: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7fefbfeb45a0 classname=virKeepAlive
>2013-11-25 08:26:02.002+0000: 3913: debug : virKeepAliveNew:215 : RPC_KEEPALIVE_NEW: ka=0x7fefbfeb45a0 client=0x7fefbfeb5710
>2013-11-25 08:26:02.002+0000: 3913: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.002+0000: 3913: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.002+0000: 3913: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeb4fd0
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollDispatchHandles:482 : i=3 w=4
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollDispatchHandles:482 : i=4 w=5
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollDispatchHandles:482 : i=5 w=6
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollDispatchHandles:482 : i=7 w=8
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollDispatchHandles:482 : i=8 w=9
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollDispatchHandles:482 : i=9 w=11
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollCleanupTimeouts:527 : EVENT_POLL_PURGE_TIMEOUT: timer=3
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventRunDefaultImpl:270 : running default event implementation
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 1
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=0 w=1, f=6 e=1 d=0
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=1 w=2, f=8 e=1 d=0
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=2 w=3, f=11 e=1 d=0
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=3 w=4, f=12 e=1 d=0
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=4 w=5, f=13 e=1 d=0
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=6 w=7, f=15 e=0 d=0
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=7 w=8, f=15 e=1 d=0
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=8 w=9, f=17 e=1 d=0
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=9 w=11, f=21 e=25 d=0
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=10 w=14, f=22 e=1 d=0
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 1 timers
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollCalculateTimeout:361 : Timeout at 0 due in -1 ms
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollRunOnce:627 : EVENT_POLL_RUN: nhandles=10 timeout=-1
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollRunOnce:638 : Poll got 1 event(s)
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollDispatchTimeouts:423 : Dispatch 1
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollDispatchHandles:468 : Dispatch 10
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollDispatchHandles:482 : i=0 w=1
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollDispatchHandles:482 : i=1 w=2
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollDispatchHandles:482 : i=2 w=3
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollDispatchHandles:482 : i=3 w=4
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollDispatchHandles:482 : i=4 w=5
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollDispatchHandles:482 : i=5 w=6
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollDispatchHandles:482 : i=7 w=8
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollDispatchHandles:482 : i=8 w=9
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollDispatchHandles:482 : i=9 w=11
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollDispatchHandles:482 : i=10 w=14
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.002+0000: 3913: debug : virNetMessageDecodeLength:149 : Got length, now need 28 total (24 more)
>2013-11-25 08:26:02.002+0000: 3913: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb5800 tx=(nil)
>2013-11-25 08:26:02.002+0000: 3913: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.002+0000: 3913: debug : virNetServerClientDispatchRead:1202 : RPC_SERVER_CLIENT_MSG_RX: client=0x7fefbfeb5710 len=28 prog=536903814 vers=1 proc=66 type=0 status=0 serial=0
>2013-11-25 08:26:02.002+0000: 3913: debug : virKeepAliveCheckMessage:374 : ka=0x7fefbfeb45a0, client=0x7fefbfeb5710, msg=0x7fefbfeb5800
>2013-11-25 08:26:02.002+0000: 3913: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.002+0000: 3913: debug : virNetServerDispatchNewMessage:213 : server=0x7fefbfea6430 client=0x7fefbfeb5710 message=0x7fefbfeb5800
>2013-11-25 08:26:02.002+0000: 3913: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeafc60
>2013-11-25 08:26:02.002+0000: 3913: debug : virNetMessageNew:44 : msg=0x7fefbfeb6660 tracked=1
>2013-11-25 08:26:02.002+0000: 3913: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb6660 tx=(nil)
>2013-11-25 08:26:02.002+0000: 3913: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 1
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventRunDefaultImpl:270 : running default event implementation
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 1
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.002+0000: 3918: debug : virNetServerHandleJob:184 : server=0x7fefbfea6430 client=0x7fefbfeb5710 message=0x7fefbfeb5800 prog=0x7fefbfeafc60
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=0 w=1, f=6 e=1 d=0
>2013-11-25 08:26:02.002+0000: 3918: debug : virNetServerProgramDispatch:285 : prog=536903814 ver=1 type=0 status=0 serial=0 proc=66
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=1 w=2, f=8 e=1 d=0
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=2 w=3, f=11 e=1 d=0
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=3 w=4, f=12 e=1 d=0
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=4 w=5, f=13 e=1 d=0
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=6 w=7, f=15 e=0 d=0
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=7 w=8, f=15 e=1 d=0
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=8 w=9, f=17 e=1 d=0
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=9 w=11, f=21 e=25 d=0
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=10 w=14, f=22 e=1 d=0
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 1 timers
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollCalculateTimeout:361 : Timeout at 0 due in -1 ms
>2013-11-25 08:26:02.002+0000: 3913: debug : virEventPollRunOnce:627 : EVENT_POLL_RUN: nhandles=10 timeout=-1
>2013-11-25 08:26:02.004+0000: 3918: debug : virFileClose:90 : Closed fd 23
>2013-11-25 08:26:02.005+0000: 3918: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7fef94000940 classname=virIdentity
>2013-11-25 08:26:02.005+0000: 3918: debug : virIdentitySetAttr:282 : ident=0x7fef94000940 attribute=0 value=root
>2013-11-25 08:26:02.005+0000: 3918: debug : virIdentitySetAttr:282 : ident=0x7fef94000940 attribute=1 value=0
>2013-11-25 08:26:02.005+0000: 3918: debug : virIdentitySetAttr:282 : ident=0x7fef94000940 attribute=2 value=root
>2013-11-25 08:26:02.005+0000: 3918: debug : virIdentitySetAttr:282 : ident=0x7fef94000940 attribute=3 value=0
>2013-11-25 08:26:02.005+0000: 3918: debug : virIdentitySetAttr:282 : ident=0x7fef94000940 attribute=4 value=4213
>2013-11-25 08:26:02.005+0000: 3918: debug : virIdentitySetAttr:282 : ident=0x7fef94000940 attribute=5 value=178337
>2013-11-25 08:26:02.005+0000: 3918: debug : virIdentitySetAttr:282 : ident=0x7fef94000940 attribute=8 value=unconfined_u:unconfined_r:unconfined_t:s0-s0:c0.c1023
>2013-11-25 08:26:02.005+0000: 3918: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fef94000940
>2013-11-25 08:26:02.005+0000: 3918: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fef94000940
>2013-11-25 08:26:02.005+0000: 3918: debug : remoteDispatchAuthListHelper:18 : server=0x7fefbfea6430 client=0x7fefbfeb5710 msg=0x7fefbfeb5800 rerr=0x7fefad5a5c90 args=0x7fef94000c80 ret=0x7fef94000ca0
>2013-11-25 08:26:02.005+0000: 3918: debug : virFileClose:90 : Closed fd 23
>2013-11-25 08:26:02.005+0000: 3918: info : remoteDispatchAuthList:2329 : Bypass polkit auth for privileged client pid:4213,uid:0
>2013-11-25 08:26:02.005+0000: 3918: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fef94000940
>2013-11-25 08:26:02.005+0000: 3918: debug : virNetMessageEncodePayload:374 : Encode length as 36
>2013-11-25 08:26:02.005+0000: 3918: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fef94000940
>2013-11-25 08:26:02.005+0000: 3918: debug : virNetServerClientSendMessageLocked:1451 : msg=0x7fefbfeb5800 proc=66 len=36 offset=0
>2013-11-25 08:26:02.005+0000: 3918: debug : virNetServerClientSendMessageLocked:1459 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7fefbfeb5710 len=36 prog=536903814 vers=1 proc=66 type=1 status=0 serial=0
>2013-11-25 08:26:02.005+0000: 3918: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb6660 tx=0x7fefbfeb5800
>2013-11-25 08:26:02.005+0000: 3918: debug : virNetServerClientCalculateHandleMode:188 : mode=3
>2013-11-25 08:26:02.005+0000: 3918: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=3
>2013-11-25 08:26:02.005+0000: 3918: debug : virEventPollInterruptLocked:714 : Interrupting
>2013-11-25 08:26:02.006+0000: 3918: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeafc60
>2013-11-25 08:26:02.006+0000: 3918: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollRunOnce:638 : Poll got 1 event(s)
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchTimeouts:423 : Dispatch 1
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:468 : Dispatch 10
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=0 w=1
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=1 w=2
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=2 w=3
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=3 w=4
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=4 w=5
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=5 w=6
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=7 w=8
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=8 w=9
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=9 w=11
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=10 w=14
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 1
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventRunDefaultImpl:270 : running default event implementation
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 1
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=0 w=1, f=6 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=1 w=2, f=8 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=2 w=3, f=11 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=3 w=4, f=12 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=4 w=5, f=13 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=6 w=7, f=15 e=0 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=7 w=8, f=15 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=8 w=9, f=17 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=9 w=11, f=21 e=25 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=10 w=14, f=22 e=5 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 1 timers
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollCalculateTimeout:361 : Timeout at 0 due in -1 ms
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollRunOnce:627 : EVENT_POLL_RUN: nhandles=10 timeout=-1
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollRunOnce:638 : Poll got 1 event(s)
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchTimeouts:423 : Dispatch 1
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:468 : Dispatch 10
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=0 w=1
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=1 w=2
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=2 w=3
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=3 w=4
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=4 w=5
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=5 w=6
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=7 w=8
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=8 w=9
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=9 w=11
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=10 w=14
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=14 events=2
>2013-11-25 08:26:02.006+0000: 3913: debug : virNetMessageFree:72 : msg=0x7fefbfeb5800 nfds=0 cb=(nil)
>2013-11-25 08:26:02.006+0000: 3913: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb6660 tx=(nil)
>2013-11-25 08:26:02.006+0000: 3913: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 1
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventRunDefaultImpl:270 : running default event implementation
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 1
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=0 w=1, f=6 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=1 w=2, f=8 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=2 w=3, f=11 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=3 w=4, f=12 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=4 w=5, f=13 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=6 w=7, f=15 e=0 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=7 w=8, f=15 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=8 w=9, f=17 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=9 w=11, f=21 e=25 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=10 w=14, f=22 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 1 timers
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollCalculateTimeout:361 : Timeout at 0 due in -1 ms
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollRunOnce:627 : EVENT_POLL_RUN: nhandles=10 timeout=-1
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollRunOnce:638 : Poll got 1 event(s)
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchTimeouts:423 : Dispatch 1
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:468 : Dispatch 10
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=0 w=1
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=1 w=2
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=2 w=3
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=3 w=4
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=4 w=5
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=5 w=6
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=7 w=8
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=8 w=9
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=9 w=11
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:482 : i=10 w=14
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.006+0000: 3913: debug : virNetMessageDecodeLength:149 : Got length, now need 32 total (28 more)
>2013-11-25 08:26:02.006+0000: 3913: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb6660 tx=(nil)
>2013-11-25 08:26:02.006+0000: 3913: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.006+0000: 3913: debug : virNetServerClientDispatchRead:1202 : RPC_SERVER_CLIENT_MSG_RX: client=0x7fefbfeb5710 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=1
>2013-11-25 08:26:02.006+0000: 3913: debug : virKeepAliveCheckMessage:374 : ka=0x7fefbfeb45a0, client=0x7fefbfeb5710, msg=0x7fefbfeb6660
>2013-11-25 08:26:02.006+0000: 3913: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.006+0000: 3913: debug : virNetServerDispatchNewMessage:213 : server=0x7fefbfea6430 client=0x7fefbfeb5710 message=0x7fefbfeb6660
>2013-11-25 08:26:02.006+0000: 3913: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeafc60
>2013-11-25 08:26:02.006+0000: 3913: debug : virNetMessageNew:44 : msg=0x7fefbfeb4130 tracked=1
>2013-11-25 08:26:02.006+0000: 3913: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb4130 tx=(nil)
>2013-11-25 08:26:02.006+0000: 3913: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 1
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventRunDefaultImpl:270 : running default event implementation
>2013-11-25 08:26:02.006+0000: 3917: debug : virNetServerHandleJob:184 : server=0x7fefbfea6430 client=0x7fefbfeb5710 message=0x7fefbfeb6660 prog=0x7fefbfeafc60
>2013-11-25 08:26:02.006+0000: 3917: debug : virNetServerProgramDispatch:285 : prog=536903814 ver=1 type=0 status=0 serial=1 proc=60
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 1
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.006+0000: 3917: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fef94000940
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=0 w=1, f=6 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=1 w=2, f=8 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=2 w=3, f=11 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3917: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fef94000940
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=3 w=4, f=12 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=4 w=5, f=13 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=6 w=7, f=15 e=0 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=7 w=8, f=15 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=8 w=9, f=17 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=9 w=11, f=21 e=25 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=10 w=14, f=22 e=1 d=0
>2013-11-25 08:26:02.006+0000: 3913: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 1 timers
>2013-11-25 08:26:02.006+0000: 3917: debug : remoteDispatchConnectSupportsFeatureHelper:2200 : server=0x7fefbfea6430 client=0x7fefbfeb5710 msg=0x7fefbfeb6660 rerr=0x7fefadda6c90 args=0x7fef88000ae0 ret=0x7fef88001a90
>2013-11-25 08:26:02.007+0000: 3917: debug : virKeepAliveStart:276 : RPC_KEEPALIVE_START: ka=0x7fefbfeb45a0 client=0x7fefbfeb5710 interval=0 count=0
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollCalculateTimeout:361 : Timeout at 0 due in -1 ms
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollRunOnce:627 : EVENT_POLL_RUN: nhandles=10 timeout=-1
>2013-11-25 08:26:02.007+0000: 3917: debug : virEventPollInterruptLocked:714 : Interrupting
>2013-11-25 08:26:02.007+0000: 3917: debug : virEventPollAddTimeout:248 : EVENT_POLL_ADD_TIMEOUT: timer=6 frequency=5000 cb=0x7fefbdd124a0 opaque=0x7fefbfeb45a0 ff=0x7fefbdc25680
>2013-11-25 08:26:02.007+0000: 3917: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeb45a0
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollRunOnce:638 : Poll got 1 event(s)
>2013-11-25 08:26:02.007+0000: 3917: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fef94000940
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchTimeouts:423 : Dispatch 2
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:468 : Dispatch 10
>2013-11-25 08:26:02.007+0000: 3917: debug : virNetMessageEncodePayload:374 : Encode length as 32
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=0 w=1
>2013-11-25 08:26:02.007+0000: 3917: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fef94000940
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2013-11-25 08:26:02.007+0000: 3917: debug : virNetServerClientSendMessageLocked:1451 : msg=0x7fefbfeb6660 proc=60 len=32 offset=0
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=1 w=2
>2013-11-25 08:26:02.007+0000: 3917: debug : virNetServerClientSendMessageLocked:1459 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7fefbfeb5710 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=1
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=2 w=3
>2013-11-25 08:26:02.007+0000: 3917: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb4130 tx=0x7fefbfeb6660
>2013-11-25 08:26:02.007+0000: 3917: debug : virNetServerClientCalculateHandleMode:188 : mode=3
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=3 w=4
>2013-11-25 08:26:02.007+0000: 3917: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=3
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=4 w=5
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=5 w=6
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=7 w=8
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=8 w=9
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=9 w=11
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=10 w=14
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.007+0000: 3917: debug : virEventPollInterruptLocked:710 : Skip interrupt, 0 140667668162688
>2013-11-25 08:26:02.007+0000: 3917: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeafc60
>2013-11-25 08:26:02.007+0000: 3917: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventRunDefaultImpl:270 : running default event implementation
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=0 w=1, f=6 e=1 d=0
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=1 w=2, f=8 e=1 d=0
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=2 w=3, f=11 e=1 d=0
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=3 w=4, f=12 e=1 d=0
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=4 w=5, f=13 e=1 d=0
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=6 w=7, f=15 e=0 d=0
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=7 w=8, f=15 e=1 d=0
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=8 w=9, f=17 e=1 d=0
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=9 w=11, f=21 e=25 d=0
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=10 w=14, f=22 e=5 d=0
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 2 timers
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1385367967007
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1385367967007 now=1385367962007
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollCalculateTimeout:361 : Timeout at 1385367967007 due in 5000 ms
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollRunOnce:627 : EVENT_POLL_RUN: nhandles=10 timeout=5000
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollRunOnce:638 : Poll got 1 event(s)
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchTimeouts:423 : Dispatch 2
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:468 : Dispatch 10
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=0 w=1
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=1 w=2
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=2 w=3
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=3 w=4
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=4 w=5
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=5 w=6
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=7 w=8
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=8 w=9
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=9 w=11
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=10 w=14
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=14 events=2
>2013-11-25 08:26:02.007+0000: 3913: debug : virNetMessageFree:72 : msg=0x7fefbfeb6660 nfds=0 cb=(nil)
>2013-11-25 08:26:02.007+0000: 3913: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb4130 tx=(nil)
>2013-11-25 08:26:02.007+0000: 3913: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventRunDefaultImpl:270 : running default event implementation
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=0 w=1, f=6 e=1 d=0
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=1 w=2, f=8 e=1 d=0
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=2 w=3, f=11 e=1 d=0
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=3 w=4, f=12 e=1 d=0
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=4 w=5, f=13 e=1 d=0
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=6 w=7, f=15 e=0 d=0
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=7 w=8, f=15 e=1 d=0
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=8 w=9, f=17 e=1 d=0
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=9 w=11, f=21 e=25 d=0
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=10 w=14, f=22 e=1 d=0
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 2 timers
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1385367967007
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1385367967007 now=1385367962007
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollCalculateTimeout:361 : Timeout at 1385367967007 due in 5000 ms
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollRunOnce:627 : EVENT_POLL_RUN: nhandles=10 timeout=5000
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollRunOnce:638 : Poll got 1 event(s)
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchTimeouts:423 : Dispatch 2
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:468 : Dispatch 10
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=0 w=1
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=1 w=2
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=2 w=3
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=3 w=4
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=4 w=5
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=5 w=6
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=7 w=8
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=8 w=9
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=9 w=11
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:482 : i=10 w=14
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.007+0000: 3913: debug : virNetMessageDecodeLength:149 : Got length, now need 40 total (36 more)
>2013-11-25 08:26:02.007+0000: 3913: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb4130 tx=(nil)
>2013-11-25 08:26:02.007+0000: 3913: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.007+0000: 3913: debug : virNetServerClientDispatchRead:1202 : RPC_SERVER_CLIENT_MSG_RX: client=0x7fefbfeb5710 len=40 prog=536903814 vers=1 proc=1 type=0 status=0 serial=2
>2013-11-25 08:26:02.007+0000: 3913: debug : virKeepAliveCheckMessage:374 : ka=0x7fefbfeb45a0, client=0x7fefbfeb5710, msg=0x7fefbfeb4130
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollUpdateTimeout:260 : EVENT_POLL_UPDATE_TIMEOUT: timer=6 frequency=5000
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollUpdateTimeout:278 : Set timer freq=5000 expires=1385367967007
>2013-11-25 08:26:02.007+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.007+0000: 3913: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.007+0000: 3913: debug : virNetServerDispatchNewMessage:213 : server=0x7fefbfea6430 client=0x7fefbfeb5710 message=0x7fefbfeb4130
>2013-11-25 08:26:02.007+0000: 3913: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeafc60
>2013-11-25 08:26:02.007+0000: 3913: debug : virNetMessageNew:44 : msg=0x7fefbfeb52f0 tracked=1
>2013-11-25 08:26:02.007+0000: 3914: debug : virNetServerHandleJob:184 : server=0x7fefbfea6430 client=0x7fefbfeb5710 message=0x7fefbfeb4130 prog=0x7fefbfeafc60
>2013-11-25 08:26:02.007+0000: 3913: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb52f0 tx=(nil)
>2013-11-25 08:26:02.007+0000: 3914: debug : virNetServerProgramDispatch:285 : prog=536903814 ver=1 type=0 status=0 serial=2 proc=1
>2013-11-25 08:26:02.007+0000: 3913: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.008+0000: 3914: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fef94000940
>2013-11-25 08:26:02.008+0000: 3914: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fef94000940
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventRunDefaultImpl:270 : running default event implementation
>2013-11-25 08:26:02.008+0000: 3914: debug : remoteDispatchConnectOpenHelper:2178 : server=0x7fefbfea6430 client=0x7fefbfeb5710 msg=0x7fefbfeb4130 rerr=0x7fefaf5a9c90 args=0x7fef98001890 ret=0x7fef98001820
>2013-11-25 08:26:02.008+0000: 3914: debug : remoteDispatchConnectOpen:737 : priv=0x7fefbfeb4210 conn=(nil)
>2013-11-25 08:26:02.008+0000: 3914: debug : virConnectOpen:1405 : name=
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.008+0000: 3914: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7fef98001b30 classname=virConnect
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.008+0000: 3914: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7fef98001580 classname=virConnectCloseCallbackData
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=0 w=1, f=6 e=1 d=0
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=1 w=2, f=8 e=1 d=0
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=2 w=3, f=11 e=1 d=0
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=3 w=4, f=12 e=1 d=0
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=4 w=5, f=13 e=1 d=0
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=6 w=7, f=15 e=0 d=0
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=7 w=8, f=15 e=1 d=0
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=8 w=9, f=17 e=1 d=0
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=9 w=11, f=21 e=25 d=0
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=10 w=14, f=22 e=1 d=0
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 2 timers
>2013-11-25 08:26:02.008+0000: 3914: debug : virConnectGetConfigFile:1014 : Loading config file '/etc/libvirt/libvirt.conf'
>2013-11-25 08:26:02.008+0000: 3914: debug : virConfReadFile:745 : filename=/etc/libvirt/libvirt.conf
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1385367967007
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1385367967007 now=1385367962008
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollCalculateTimeout:361 : Timeout at 1385367967007 due in 4999 ms
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollRunOnce:627 : EVENT_POLL_RUN: nhandles=10 timeout=4999
>2013-11-25 08:26:02.008+0000: 3914: debug : virFileClose:90 : Closed fd 23
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1195 : no name, allowing driver auto-select
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1237 : trying driver 0 (Test) ...
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1244 : driver 0 Test returned DECLINED
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1237 : trying driver 1 (ESX) ...
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1244 : driver 1 ESX returned DECLINED
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1237 : trying driver 2 (remote) ...
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1244 : driver 2 remote returned DECLINED
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1237 : trying driver 3 (QEMU) ...
>2013-11-25 08:26:02.008+0000: 3914: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefa0146010
>2013-11-25 08:26:02.008+0000: 3914: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeb1aa0
>2013-11-25 08:26:02.008+0000: 3914: debug : virAccessManagerCheckConnect:215 : manager=0x7fefbfeb1aa0(name=stack) driver=QEMU perm=0
>2013-11-25 08:26:02.008+0000: 3914: debug : virAccessManagerCheckConnect:215 : manager=0x7fefbfea60a0(name=none) driver=QEMU perm=0
>2013-11-25 08:26:02.008+0000: 3914: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeb1aa0
>2013-11-25 08:26:02.008+0000: 3914: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefa0146010
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1244 : driver 3 QEMU returned SUCCESS
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1270 : network driver 0 Test returned DECLINED
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1270 : network driver 1 ESX returned DECLINED
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1270 : network driver 2 remote returned DECLINED
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1270 : network driver 3 Network returned SUCCESS
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1286 : interface driver 0 Test returned DECLINED
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1286 : interface driver 1 ESX returned DECLINED
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1286 : interface driver 2 remote returned DECLINED
>2013-11-25 08:26:02.008+0000: 3914: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefa000f370
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1286 : interface driver 3 netcf returned SUCCESS
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1303 : storage driver 0 Test returned DECLINED
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1303 : storage driver 1 ESX returned DECLINED
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1303 : storage driver 2 remote returned DECLINED
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1303 : storage driver 3 storage returned SUCCESS
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1320 : node driver 0 Test returned DECLINED
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1320 : node driver 1 ESX returned DECLINED
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1320 : node driver 2 remote returned DECLINED
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1320 : node driver 3 udevNodeDeviceDriver returned SUCCESS
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1337 : secret driver 0 Test returned DECLINED
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1337 : secret driver 1 ESX returned DECLINED
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1337 : secret driver 2 remote returned DECLINED
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1337 : secret driver 3 secret returned SUCCESS
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1354 : nwfilter driver 0 Test returned DECLINED
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1354 : nwfilter driver 1 ESX returned DECLINED
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1354 : nwfilter driver 2 remote returned DECLINED
>2013-11-25 08:26:02.008+0000: 3914: debug : do_open:1354 : nwfilter driver 3 nwfilter returned SUCCESS
>2013-11-25 08:26:02.008+0000: 3914: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fef94000940
>2013-11-25 08:26:02.008+0000: 3914: debug : virNetMessageEncodePayload:374 : Encode length as 28
>2013-11-25 08:26:02.008+0000: 3914: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fef94000940
>2013-11-25 08:26:02.008+0000: 3914: debug : virNetServerClientSendMessageLocked:1451 : msg=0x7fefbfeb4130 proc=1 len=28 offset=0
>2013-11-25 08:26:02.008+0000: 3914: debug : virNetServerClientSendMessageLocked:1459 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7fefbfeb5710 len=28 prog=536903814 vers=1 proc=1 type=1 status=0 serial=2
>2013-11-25 08:26:02.008+0000: 3914: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb52f0 tx=0x7fefbfeb4130
>2013-11-25 08:26:02.008+0000: 3914: debug : virNetServerClientCalculateHandleMode:188 : mode=3
>2013-11-25 08:26:02.008+0000: 3914: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=3
>2013-11-25 08:26:02.008+0000: 3914: debug : virEventPollInterruptLocked:714 : Interrupting
>2013-11-25 08:26:02.008+0000: 3914: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeafc60
>2013-11-25 08:26:02.008+0000: 3914: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollRunOnce:638 : Poll got 1 event(s)
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchTimeouts:423 : Dispatch 2
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchHandles:468 : Dispatch 10
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchHandles:482 : i=0 w=1
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchHandles:482 : i=1 w=2
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchHandles:482 : i=2 w=3
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchHandles:482 : i=3 w=4
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchHandles:482 : i=4 w=5
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchHandles:482 : i=5 w=6
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchHandles:482 : i=7 w=8
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchHandles:482 : i=8 w=9
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchHandles:482 : i=9 w=11
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchHandles:482 : i=10 w=14
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventRunDefaultImpl:270 : running default event implementation
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=0 w=1, f=6 e=1 d=0
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=1 w=2, f=8 e=1 d=0
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=2 w=3, f=11 e=1 d=0
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=3 w=4, f=12 e=1 d=0
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=4 w=5, f=13 e=1 d=0
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=6 w=7, f=15 e=0 d=0
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=7 w=8, f=15 e=1 d=0
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=8 w=9, f=17 e=1 d=0
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=9 w=11, f=21 e=25 d=0
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=10 w=14, f=22 e=5 d=0
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 2 timers
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1385367967007
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1385367967007 now=1385367962008
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollCalculateTimeout:361 : Timeout at 1385367967007 due in 4999 ms
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollRunOnce:627 : EVENT_POLL_RUN: nhandles=10 timeout=4999
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollRunOnce:638 : Poll got 1 event(s)
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchTimeouts:423 : Dispatch 2
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchHandles:468 : Dispatch 10
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchHandles:482 : i=0 w=1
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchHandles:482 : i=1 w=2
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchHandles:482 : i=2 w=3
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchHandles:482 : i=3 w=4
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchHandles:482 : i=4 w=5
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchHandles:482 : i=5 w=6
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchHandles:482 : i=7 w=8
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchHandles:482 : i=8 w=9
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchHandles:482 : i=9 w=11
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchHandles:482 : i=10 w=14
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=14 events=2
>2013-11-25 08:26:02.008+0000: 3913: debug : virNetMessageFree:72 : msg=0x7fefbfeb4130 nfds=0 cb=(nil)
>2013-11-25 08:26:02.008+0000: 3913: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb52f0 tx=(nil)
>2013-11-25 08:26:02.008+0000: 3913: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.008+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventRunDefaultImpl:270 : running default event implementation
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=0 w=1, f=6 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=1 w=2, f=8 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=2 w=3, f=11 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=3 w=4, f=12 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=4 w=5, f=13 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=6 w=7, f=15 e=0 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=7 w=8, f=15 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=8 w=9, f=17 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=9 w=11, f=21 e=25 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=10 w=14, f=22 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 2 timers
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1385367967007
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1385367967007 now=1385367962009
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollCalculateTimeout:361 : Timeout at 1385367967007 due in 4998 ms
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollRunOnce:627 : EVENT_POLL_RUN: nhandles=10 timeout=4998
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollRunOnce:638 : Poll got 1 event(s)
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchTimeouts:423 : Dispatch 2
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:468 : Dispatch 10
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=0 w=1
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=1 w=2
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=2 w=3
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=3 w=4
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=4 w=5
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=5 w=6
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=7 w=8
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=8 w=9
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=9 w=11
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=10 w=14
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.009+0000: 3913: debug : virNetMessageDecodeLength:149 : Got length, now need 28 total (24 more)
>2013-11-25 08:26:02.009+0000: 3913: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb52f0 tx=(nil)
>2013-11-25 08:26:02.009+0000: 3913: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.009+0000: 3913: debug : virNetServerClientDispatchRead:1202 : RPC_SERVER_CLIENT_MSG_RX: client=0x7fefbfeb5710 len=28 prog=536903814 vers=1 proc=110 type=0 status=0 serial=3
>2013-11-25 08:26:02.009+0000: 3913: debug : virKeepAliveCheckMessage:374 : ka=0x7fefbfeb45a0, client=0x7fefbfeb5710, msg=0x7fefbfeb52f0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollUpdateTimeout:260 : EVENT_POLL_UPDATE_TIMEOUT: timer=6 frequency=5000
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollUpdateTimeout:278 : Set timer freq=5000 expires=1385367967009
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.009+0000: 3913: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.009+0000: 3913: debug : virNetServerDispatchNewMessage:213 : server=0x7fefbfea6430 client=0x7fefbfeb5710 message=0x7fefbfeb52f0
>2013-11-25 08:26:02.009+0000: 3913: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeafc60
>2013-11-25 08:26:02.009+0000: 3913: debug : virNetMessageNew:44 : msg=0x7fefbfeb43f0 tracked=1
>2013-11-25 08:26:02.009+0000: 3913: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb43f0 tx=(nil)
>2013-11-25 08:26:02.009+0000: 3913: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventRunDefaultImpl:270 : running default event implementation
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.009+0000: 3919: debug : virNetServerHandleJob:184 : server=0x7fefbfea6430 client=0x7fefbfeb5710 message=0x7fefbfeb52f0 prog=0x7fefbfeafc60
>2013-11-25 08:26:02.009+0000: 3919: debug : virNetServerProgramDispatch:285 : prog=536903814 ver=1 type=0 status=0 serial=3 proc=110
>2013-11-25 08:26:02.009+0000: 3919: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fef94000940
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=0 w=1, f=6 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3919: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fef94000940
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=1 w=2, f=8 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3919: debug : remoteDispatchConnectGetURIHelper:780 : server=0x7fefbfea6430 client=0x7fefbfeb5710 msg=0x7fefbfeb52f0 rerr=0x7fefacda4c90 args=0x7fefa041be20 ret=0x7fefa041bdb0
>2013-11-25 08:26:02.009+0000: 3919: debug : virConnectGetURI:1774 : conn=0x7fef98001b30
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=2 w=3, f=11 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3919: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fef94000940
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=3 w=4, f=12 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=4 w=5, f=13 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3919: debug : virNetMessageEncodePayload:374 : Encode length as 48
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3919: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fef94000940
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=6 w=7, f=15 e=0 d=0
>2013-11-25 08:26:02.009+0000: 3919: debug : virNetServerClientSendMessageLocked:1451 : msg=0x7fefbfeb52f0 proc=110 len=48 offset=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=7 w=8, f=15 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3919: debug : virNetServerClientSendMessageLocked:1459 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7fefbfeb5710 len=48 prog=536903814 vers=1 proc=110 type=1 status=0 serial=3
>2013-11-25 08:26:02.009+0000: 3919: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb43f0 tx=0x7fefbfeb52f0
>2013-11-25 08:26:02.009+0000: 3919: debug : virNetServerClientCalculateHandleMode:188 : mode=3
>2013-11-25 08:26:02.009+0000: 3919: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=3
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=8 w=9, f=17 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=9 w=11, f=21 e=25 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=10 w=14, f=22 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 2 timers
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1385367967009
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1385367967009 now=1385367962009
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollCalculateTimeout:361 : Timeout at 1385367967009 due in 5000 ms
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollRunOnce:627 : EVENT_POLL_RUN: nhandles=10 timeout=5000
>2013-11-25 08:26:02.009+0000: 3919: debug : virEventPollInterruptLocked:714 : Interrupting
>2013-11-25 08:26:02.009+0000: 3919: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeafc60
>2013-11-25 08:26:02.009+0000: 3919: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollRunOnce:638 : Poll got 1 event(s)
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchTimeouts:423 : Dispatch 2
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:468 : Dispatch 10
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=0 w=1
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=1 w=2
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=2 w=3
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=3 w=4
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=4 w=5
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=5 w=6
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=7 w=8
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=8 w=9
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=9 w=11
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=10 w=14
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventRunDefaultImpl:270 : running default event implementation
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=0 w=1, f=6 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=1 w=2, f=8 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=2 w=3, f=11 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=3 w=4, f=12 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=4 w=5, f=13 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=6 w=7, f=15 e=0 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=7 w=8, f=15 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=8 w=9, f=17 e=1 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=9 w=11, f=21 e=25 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=10 w=14, f=22 e=5 d=0
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 2 timers
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1385367967009
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1385367967009 now=1385367962009
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollCalculateTimeout:361 : Timeout at 1385367967009 due in 5000 ms
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollRunOnce:627 : EVENT_POLL_RUN: nhandles=10 timeout=5000
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollRunOnce:638 : Poll got 1 event(s)
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchTimeouts:423 : Dispatch 2
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:468 : Dispatch 10
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=0 w=1
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=1 w=2
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=2 w=3
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=3 w=4
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=4 w=5
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=5 w=6
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=7 w=8
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=8 w=9
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=9 w=11
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:482 : i=10 w=14
>2013-11-25 08:26:02.009+0000: 3913: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=14 events=2
>2013-11-25 08:26:02.010+0000: 3913: debug : virNetMessageFree:72 : msg=0x7fefbfeb52f0 nfds=0 cb=(nil)
>2013-11-25 08:26:02.010+0000: 3913: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb43f0 tx=(nil)
>2013-11-25 08:26:02.010+0000: 3913: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventRunDefaultImpl:270 : running default event implementation
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=0 w=1, f=6 e=1 d=0
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=1 w=2, f=8 e=1 d=0
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=2 w=3, f=11 e=1 d=0
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=3 w=4, f=12 e=1 d=0
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=4 w=5, f=13 e=1 d=0
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=6 w=7, f=15 e=0 d=0
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=7 w=8, f=15 e=1 d=0
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=8 w=9, f=17 e=1 d=0
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=9 w=11, f=21 e=25 d=0
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=10 w=14, f=22 e=1 d=0
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 2 timers
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1385367967009
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1385367967009 now=1385367962010
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollCalculateTimeout:361 : Timeout at 1385367967009 due in 4999 ms
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollRunOnce:627 : EVENT_POLL_RUN: nhandles=10 timeout=4999
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollRunOnce:638 : Poll got 1 event(s)
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchTimeouts:423 : Dispatch 2
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchHandles:468 : Dispatch 10
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchHandles:482 : i=0 w=1
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchHandles:482 : i=1 w=2
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchHandles:482 : i=2 w=3
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchHandles:482 : i=3 w=4
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchHandles:482 : i=4 w=5
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchHandles:482 : i=5 w=6
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchHandles:482 : i=7 w=8
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchHandles:482 : i=8 w=9
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchHandles:482 : i=9 w=11
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchHandles:482 : i=10 w=14
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.010+0000: 3913: debug : virNetMessageDecodeLength:149 : Got length, now need 36 total (32 more)
>2013-11-25 08:26:02.010+0000: 3913: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb43f0 tx=(nil)
>2013-11-25 08:26:02.010+0000: 3913: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.010+0000: 3913: debug : virNetServerClientDispatchRead:1202 : RPC_SERVER_CLIENT_MSG_RX: client=0x7fefbfeb5710 len=36 prog=536903814 vers=1 proc=23 type=0 status=0 serial=4
>2013-11-25 08:26:02.010+0000: 3913: debug : virKeepAliveCheckMessage:374 : ka=0x7fefbfeb45a0, client=0x7fefbfeb5710, msg=0x7fefbfeb43f0
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollUpdateTimeout:260 : EVENT_POLL_UPDATE_TIMEOUT: timer=6 frequency=5000
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollUpdateTimeout:278 : Set timer freq=5000 expires=1385367967010
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.010+0000: 3913: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.010+0000: 3913: debug : virNetServerDispatchNewMessage:213 : server=0x7fefbfea6430 client=0x7fefbfeb5710 message=0x7fefbfeb43f0
>2013-11-25 08:26:02.010+0000: 3913: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeafc60
>2013-11-25 08:26:02.010+0000: 3913: debug : virNetMessageNew:44 : msg=0x7fefbfeb4100 tracked=1
>2013-11-25 08:26:02.010+0000: 3913: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb4100 tx=(nil)
>2013-11-25 08:26:02.010+0000: 3913: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventRunDefaultImpl:270 : running default event implementation
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.010+0000: 3916: debug : virNetServerHandleJob:184 : server=0x7fefbfea6430 client=0x7fefbfeb5710 message=0x7fefbfeb43f0 prog=0x7fefbfeafc60
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=0 w=1, f=6 e=1 d=0
>2013-11-25 08:26:02.010+0000: 3916: debug : virNetServerProgramDispatch:285 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=23
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=1 w=2, f=8 e=1 d=0
>2013-11-25 08:26:02.010+0000: 3916: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fef94000940
>2013-11-25 08:26:02.010+0000: 3916: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fef94000940
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=2 w=3, f=11 e=1 d=0
>2013-11-25 08:26:02.010+0000: 3916: debug : remoteDispatchDomainLookupByNameHelper:4943 : server=0x7fefbfea6430 client=0x7fefbfeb5710 msg=0x7fefbfeb43f0 rerr=0x7fefae5a7c90 args=0x7fef78000900 ret=0x7fef780009a0
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=3 w=4, f=12 e=1 d=0
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=4 w=5, f=13 e=1 d=0
>2013-11-25 08:26:02.010+0000: 3916: debug : virDomainLookupByName:2264 : conn=0x7fef98001b30, name=r6.4
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-11-25 08:26:02.010+0000: 3916: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeb1aa0
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=6 w=7, f=15 e=0 d=0
>2013-11-25 08:26:02.010+0000: 3916: debug : virAccessManagerCheckDomain:231 : manager=0x7fefbfeb1aa0(name=stack) driver=QEMU domain=0x7fefa02ef920 perm=0
>2013-11-25 08:26:02.010+0000: 3916: debug : virAccessManagerCheckDomain:231 : manager=0x7fefbfea60a0(name=none) driver=QEMU domain=0x7fefa02ef920 perm=0
>2013-11-25 08:26:02.010+0000: 3916: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeb1aa0
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=7 w=8, f=15 e=1 d=0
>2013-11-25 08:26:02.010+0000: 3916: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7fef780008c0 classname=virDomain
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=8 w=9, f=17 e=1 d=0
>2013-11-25 08:26:02.010+0000: 3916: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fef98001b30
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=9 w=11, f=21 e=25 d=0
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=10 w=14, f=22 e=1 d=0
>2013-11-25 08:26:02.010+0000: 3916: debug : virDomainFree:2428 : dom=0x7fef780008c0, (VM: name=r6.4, uuid=a3559509-df36-4001-a4a2-f7f79ac049d2)
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 2 timers
>2013-11-25 08:26:02.010+0000: 3916: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fef780008c0
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1385367967010
>2013-11-25 08:26:02.010+0000: 3916: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7fef780008c0
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1385367967010 now=1385367962010
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollCalculateTimeout:361 : Timeout at 1385367967010 due in 5000 ms
>2013-11-25 08:26:02.010+0000: 3916: debug : virDomainDispose:262 : release domain 0x7fef780008c0 r6.4 a3559509-df36-4001-a4a2-f7f79ac049d2
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollRunOnce:627 : EVENT_POLL_RUN: nhandles=10 timeout=5000
>2013-11-25 08:26:02.010+0000: 3916: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fef98001b30
>2013-11-25 08:26:02.010+0000: 3916: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fef94000940
>2013-11-25 08:26:02.010+0000: 3916: debug : virNetMessageEncodePayload:374 : Encode length as 56
>2013-11-25 08:26:02.010+0000: 3916: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fef94000940
>2013-11-25 08:26:02.010+0000: 3916: debug : virNetServerClientSendMessageLocked:1451 : msg=0x7fefbfeb43f0 proc=23 len=56 offset=0
>2013-11-25 08:26:02.010+0000: 3916: debug : virNetServerClientSendMessageLocked:1459 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7fefbfeb5710 len=56 prog=536903814 vers=1 proc=23 type=1 status=0 serial=4
>2013-11-25 08:26:02.010+0000: 3916: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb4100 tx=0x7fefbfeb43f0
>2013-11-25 08:26:02.010+0000: 3916: debug : virNetServerClientCalculateHandleMode:188 : mode=3
>2013-11-25 08:26:02.010+0000: 3916: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=3
>2013-11-25 08:26:02.010+0000: 3916: debug : virEventPollInterruptLocked:714 : Interrupting
>2013-11-25 08:26:02.010+0000: 3916: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeafc60
>2013-11-25 08:26:02.010+0000: 3916: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollRunOnce:638 : Poll got 1 event(s)
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchTimeouts:423 : Dispatch 2
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchHandles:468 : Dispatch 10
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchHandles:482 : i=0 w=1
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchHandles:482 : i=1 w=2
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchHandles:482 : i=2 w=3
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchHandles:482 : i=3 w=4
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchHandles:482 : i=4 w=5
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchHandles:482 : i=5 w=6
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchHandles:482 : i=7 w=8
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchHandles:482 : i=8 w=9
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchHandles:482 : i=9 w=11
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollDispatchHandles:482 : i=10 w=14
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventRunDefaultImpl:270 : running default event implementation
>2013-11-25 08:26:02.010+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=0 w=1, f=6 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=1 w=2, f=8 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=2 w=3, f=11 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=3 w=4, f=12 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=4 w=5, f=13 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=6 w=7, f=15 e=0 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=7 w=8, f=15 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=8 w=9, f=17 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=9 w=11, f=21 e=25 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=10 w=14, f=22 e=5 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 2 timers
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1385367967010
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1385367967010 now=1385367962011
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollCalculateTimeout:361 : Timeout at 1385367967010 due in 4999 ms
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollRunOnce:627 : EVENT_POLL_RUN: nhandles=10 timeout=4999
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollRunOnce:638 : Poll got 1 event(s)
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchTimeouts:423 : Dispatch 2
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchHandles:468 : Dispatch 10
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchHandles:482 : i=0 w=1
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchHandles:482 : i=1 w=2
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchHandles:482 : i=2 w=3
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchHandles:482 : i=3 w=4
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchHandles:482 : i=4 w=5
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchHandles:482 : i=5 w=6
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchHandles:482 : i=7 w=8
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchHandles:482 : i=8 w=9
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchHandles:482 : i=9 w=11
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchHandles:482 : i=10 w=14
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=14 events=2
>2013-11-25 08:26:02.011+0000: 3913: debug : virNetMessageFree:72 : msg=0x7fefbfeb43f0 nfds=0 cb=(nil)
>2013-11-25 08:26:02.011+0000: 3913: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb4100 tx=(nil)
>2013-11-25 08:26:02.011+0000: 3913: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventRunDefaultImpl:270 : running default event implementation
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=0 w=1, f=6 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=1 w=2, f=8 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=2 w=3, f=11 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=3 w=4, f=12 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=4 w=5, f=13 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=6 w=7, f=15 e=0 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=7 w=8, f=15 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=8 w=9, f=17 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=9 w=11, f=21 e=25 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=10 w=14, f=22 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 2 timers
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1385367967010
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1385367967010 now=1385367962011
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollCalculateTimeout:361 : Timeout at 1385367967010 due in 4999 ms
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollRunOnce:627 : EVENT_POLL_RUN: nhandles=10 timeout=4999
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollRunOnce:638 : Poll got 1 event(s)
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchTimeouts:423 : Dispatch 2
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchHandles:468 : Dispatch 10
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchHandles:482 : i=0 w=1
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchHandles:482 : i=1 w=2
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchHandles:482 : i=2 w=3
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchHandles:482 : i=3 w=4
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchHandles:482 : i=4 w=5
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchHandles:482 : i=5 w=6
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchHandles:482 : i=7 w=8
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchHandles:482 : i=8 w=9
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchHandles:482 : i=9 w=11
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchHandles:482 : i=10 w=14
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.011+0000: 3913: debug : virNetMessageDecodeLength:149 : Got length, now need 220 total (216 more)
>2013-11-25 08:26:02.011+0000: 3913: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb4100 tx=(nil)
>2013-11-25 08:26:02.011+0000: 3913: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.011+0000: 3913: debug : virNetServerClientDispatchRead:1202 : RPC_SERVER_CLIENT_MSG_RX: client=0x7fefbfeb5710 len=220 prog=536903814 vers=1 proc=8 type=0 status=0 serial=5
>2013-11-25 08:26:02.011+0000: 3913: debug : virKeepAliveCheckMessage:374 : ka=0x7fefbfeb45a0, client=0x7fefbfeb5710, msg=0x7fefbfeb4100
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollUpdateTimeout:260 : EVENT_POLL_UPDATE_TIMEOUT: timer=6 frequency=5000
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollUpdateTimeout:278 : Set timer freq=5000 expires=1385367967011
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.011+0000: 3913: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.011+0000: 3913: debug : virNetServerDispatchNewMessage:213 : server=0x7fefbfea6430 client=0x7fefbfeb5710 message=0x7fefbfeb4100
>2013-11-25 08:26:02.011+0000: 3913: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeafc60
>2013-11-25 08:26:02.011+0000: 3913: debug : virNetMessageNew:44 : msg=0x7fefbfeb52f0 tracked=1
>2013-11-25 08:26:02.011+0000: 3913: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb52f0 tx=(nil)
>2013-11-25 08:26:02.011+0000: 3913: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventRunDefaultImpl:270 : running default event implementation
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.011+0000: 3918: debug : virNetServerHandleJob:184 : server=0x7fefbfea6430 client=0x7fefbfeb5710 message=0x7fefbfeb4100 prog=0x7fefbfeafc60
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=0 w=1, f=6 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3918: debug : virNetServerProgramDispatch:285 : prog=536903814 ver=1 type=0 status=0 serial=5 proc=8
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=1 w=2, f=8 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=2 w=3, f=11 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=3 w=4, f=12 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3918: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fef94000940
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=4 w=5, f=13 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=6 w=7, f=15 e=0 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=7 w=8, f=15 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3918: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fef94000940
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=8 w=9, f=17 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3918: debug : remoteDispatchDomainAttachDeviceHelper:2273 : server=0x7fefbfea6430 client=0x7fefbfeb5710 msg=0x7fefbfeb4100 rerr=0x7fefad5a5c90 args=0x7fef94000b10 ret=0x7fef940009e0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=9 w=11, f=21 e=25 d=0
>2013-11-25 08:26:02.011+0000: 3918: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7fef940009a0 classname=virDomain
>2013-11-25 08:26:02.011+0000: 3918: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fef98001b30
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=10 w=14, f=22 e=1 d=0
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 2 timers
>2013-11-25 08:26:02.011+0000: 3918: debug : virDomainAttachDevice:10918 : dom=0x7fef940009a0, (VM: name=r6.4, uuid=a3559509-df36-4001-a4a2-f7f79ac049d2), xml=<hostdev mode='subsystem' type='usb' managed='yes'>
>      <source>
>        <product id='0x090c'/>
>        <vendor id='0x1000'/>
>      </source>
>    </hostdev>
>
>2013-11-25 08:26:02.011+0000: 3913: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1385367967011
>2013-11-25 08:26:02.011+0000: 3918: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefa0146010
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1385367967011 now=1385367962012
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollCalculateTimeout:361 : Timeout at 1385367967011 due in 4999 ms
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollRunOnce:627 : EVENT_POLL_RUN: nhandles=10 timeout=4999
>2013-11-25 08:26:02.012+0000: 3918: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefa02de0a0
>2013-11-25 08:26:02.012+0000: 3918: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeb1aa0
>2013-11-25 08:26:02.012+0000: 3918: debug : virAccessManagerCheckDomain:231 : manager=0x7fefbfeb1aa0(name=stack) driver=QEMU domain=0x7fefa02ef920 perm=2
>2013-11-25 08:26:02.012+0000: 3918: debug : virAccessManagerCheckDomain:231 : manager=0x7fefbfea60a0(name=none) driver=QEMU domain=0x7fefa02ef920 perm=2
>2013-11-25 08:26:02.012+0000: 3918: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeb1aa0
>2013-11-25 08:26:02.012+0000: 3918: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefa0146010
>2013-11-25 08:26:02.012+0000: 3918: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefa02e2620
>2013-11-25 08:26:02.012+0000: 3918: debug : qemuDomainObjBeginJobInternal:1038 : Starting job: modify (async=none)
>2013-11-25 08:26:02.012+0000: 3918: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefa0146010
>2013-11-25 08:26:02.012+0000: 3918: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefa02f0b10
>2013-11-25 08:26:02.012+0000: 3918: debug : virUSBDeviceNew:366 : 0 0 000:000: initialized
>2013-11-25 08:26:02.012+0000: 3918: info : virSecuritySELinuxSetFileconHelper:887 : Setting SELinux context on '/dev/bus/usb/000/000' to 'system_u:object_r:svirt_image_t:s0:c387,c595'
>2013-11-25 08:26:02.012+0000: 3918: error : virSecuritySELinuxSetFileconHelper:910 : unable to set security context 'system_u:object_r:svirt_image_t:s0:c387,c595' on '/dev/bus/usb/000/000': No such file or directory
>2013-11-25 08:26:02.012+0000: 3918: debug : virUSBDeviceFree:376 : 0 0 000:000: freeing
>2013-11-25 08:26:02.012+0000: 3918: debug : virUSBDeviceNew:366 : 0 0 000:000: initialized
>2013-11-25 08:26:02.012+0000: 3918: debug : virSecurityDACParseIds:89 : DAC seclabel for domain 'r6.4' wasn't found
>2013-11-25 08:26:02.012+0000: 3918: info : virSecurityDACSetOwnership:255 : Setting DAC user and group on '/dev/bus/usb/000/000' to '107:107'
>2013-11-25 08:26:02.012+0000: 3918: error : virSecurityDACSetOwnership:285 : unable to set user and group to '107:107' on '/dev/bus/usb/000/000': No such file or directory
>2013-11-25 08:26:02.012+0000: 3918: debug : virUSBDeviceFree:376 : 0 0 000:000: freeing
>2013-11-25 08:26:02.012+0000: 3918: debug : qemuDomainObjEndJob:1150 : Stopping job: modify (async=none)
>2013-11-25 08:26:02.012+0000: 3918: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefa02e2620
>2013-11-25 08:26:02.012+0000: 3918: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefa02f0b10
>2013-11-25 08:26:02.012+0000: 3918: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefa02de0a0
>2013-11-25 08:26:02.012+0000: 3918: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefa0146010
>2013-11-25 08:26:02.012+0000: 3918: debug : virDomainFree:2428 : dom=0x7fef940009a0, (VM: name=r6.4, uuid=a3559509-df36-4001-a4a2-f7f79ac049d2)
>2013-11-25 08:26:02.012+0000: 3918: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fef940009a0
>2013-11-25 08:26:02.012+0000: 3918: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7fef940009a0
>2013-11-25 08:26:02.012+0000: 3918: debug : virDomainDispose:262 : release domain 0x7fef940009a0 r6.4 a3559509-df36-4001-a4a2-f7f79ac049d2
>2013-11-25 08:26:02.012+0000: 3918: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fef98001b30
>2013-11-25 08:26:02.012+0000: 3918: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fef94000940
>2013-11-25 08:26:02.012+0000: 3918: debug : virNetServerProgramSendError:151 : prog=536903814 ver=1 proc=8 type=1 serial=5 msg=0x7fefbfeb4100 rerr=0x7fefad5a5c90
>2013-11-25 08:26:02.012+0000: 3918: debug : virNetMessageEncodePayload:374 : Encode length as 280
>2013-11-25 08:26:02.012+0000: 3918: debug : virNetServerClientSendMessageLocked:1451 : msg=0x7fefbfeb4100 proc=8 len=280 offset=0
>2013-11-25 08:26:02.012+0000: 3918: debug : virNetServerClientSendMessageLocked:1459 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7fefbfeb5710 len=280 prog=536903814 vers=1 proc=8 type=1 status=1 serial=5
>2013-11-25 08:26:02.012+0000: 3918: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb52f0 tx=0x7fefbfeb4100
>2013-11-25 08:26:02.012+0000: 3918: debug : virNetServerClientCalculateHandleMode:188 : mode=3
>2013-11-25 08:26:02.012+0000: 3918: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=3
>2013-11-25 08:26:02.012+0000: 3918: debug : virEventPollInterruptLocked:714 : Interrupting
>2013-11-25 08:26:02.012+0000: 3918: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fef94000940
>2013-11-25 08:26:02.012+0000: 3918: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeafc60
>2013-11-25 08:26:02.012+0000: 3918: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollRunOnce:638 : Poll got 1 event(s)
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollDispatchTimeouts:423 : Dispatch 2
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollDispatchHandles:468 : Dispatch 10
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollDispatchHandles:482 : i=0 w=1
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollDispatchHandles:482 : i=1 w=2
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollDispatchHandles:482 : i=2 w=3
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollDispatchHandles:482 : i=3 w=4
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollDispatchHandles:482 : i=4 w=5
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollDispatchHandles:482 : i=5 w=6
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollDispatchHandles:482 : i=7 w=8
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollDispatchHandles:482 : i=8 w=9
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollDispatchHandles:482 : i=9 w=11
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollDispatchHandles:482 : i=10 w=14
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventRunDefaultImpl:270 : running default event implementation
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=0 w=1, f=6 e=1 d=0
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=1 w=2, f=8 e=1 d=0
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=2 w=3, f=11 e=1 d=0
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=3 w=4, f=12 e=1 d=0
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=4 w=5, f=13 e=1 d=0
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=6 w=7, f=15 e=0 d=0
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=7 w=8, f=15 e=1 d=0
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=8 w=9, f=17 e=1 d=0
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=9 w=11, f=21 e=25 d=0
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=10 w=14, f=22 e=5 d=0
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 2 timers
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1385367967011
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1385367967011 now=1385367962012
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollCalculateTimeout:361 : Timeout at 1385367967011 due in 4999 ms
>2013-11-25 08:26:02.012+0000: 3913: debug : virEventPollRunOnce:627 : EVENT_POLL_RUN: nhandles=10 timeout=4999
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollRunOnce:638 : Poll got 1 event(s)
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchTimeouts:423 : Dispatch 2
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchHandles:468 : Dispatch 10
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchHandles:482 : i=0 w=1
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchHandles:482 : i=1 w=2
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchHandles:482 : i=2 w=3
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchHandles:482 : i=3 w=4
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchHandles:482 : i=4 w=5
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchHandles:482 : i=5 w=6
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchHandles:482 : i=7 w=8
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchHandles:482 : i=8 w=9
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchHandles:482 : i=9 w=11
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchHandles:482 : i=10 w=14
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=14 events=2
>2013-11-25 08:26:02.013+0000: 3913: debug : virNetMessageFree:72 : msg=0x7fefbfeb4100 nfds=0 cb=(nil)
>2013-11-25 08:26:02.013+0000: 3913: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb52f0 tx=(nil)
>2013-11-25 08:26:02.013+0000: 3913: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventRunDefaultImpl:270 : running default event implementation
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=0 w=1, f=6 e=1 d=0
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=1 w=2, f=8 e=1 d=0
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=2 w=3, f=11 e=1 d=0
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=3 w=4, f=12 e=1 d=0
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=4 w=5, f=13 e=1 d=0
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=6 w=7, f=15 e=0 d=0
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=7 w=8, f=15 e=1 d=0
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=8 w=9, f=17 e=1 d=0
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=9 w=11, f=21 e=25 d=0
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=10 w=14, f=22 e=1 d=0
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 2 timers
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1385367967011
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1385367967011 now=1385367962013
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollCalculateTimeout:361 : Timeout at 1385367967011 due in 4998 ms
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollRunOnce:627 : EVENT_POLL_RUN: nhandles=10 timeout=4998
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollRunOnce:638 : Poll got 1 event(s)
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchTimeouts:423 : Dispatch 2
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchHandles:468 : Dispatch 10
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchHandles:482 : i=0 w=1
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchHandles:482 : i=1 w=2
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchHandles:482 : i=2 w=3
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchHandles:482 : i=3 w=4
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchHandles:482 : i=4 w=5
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchHandles:482 : i=5 w=6
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchHandles:482 : i=7 w=8
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchHandles:482 : i=8 w=9
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchHandles:482 : i=9 w=11
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchHandles:482 : i=10 w=14
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.013+0000: 3913: debug : virNetMessageDecodeLength:149 : Got length, now need 28 total (24 more)
>2013-11-25 08:26:02.013+0000: 3913: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfeb52f0 tx=(nil)
>2013-11-25 08:26:02.013+0000: 3913: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.013+0000: 3913: debug : virNetServerClientDispatchRead:1202 : RPC_SERVER_CLIENT_MSG_RX: client=0x7fefbfeb5710 len=28 prog=536903814 vers=1 proc=2 type=0 status=0 serial=6
>2013-11-25 08:26:02.013+0000: 3913: debug : virKeepAliveCheckMessage:374 : ka=0x7fefbfeb45a0, client=0x7fefbfeb5710, msg=0x7fefbfeb52f0
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollUpdateTimeout:260 : EVENT_POLL_UPDATE_TIMEOUT: timer=6 frequency=5000
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollUpdateTimeout:278 : Set timer freq=5000 expires=1385367967013
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.013+0000: 3913: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.013+0000: 3913: debug : virNetServerDispatchNewMessage:213 : server=0x7fefbfea6430 client=0x7fefbfeb5710 message=0x7fefbfeb52f0
>2013-11-25 08:26:02.013+0000: 3913: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeafc60
>2013-11-25 08:26:02.013+0000: 3913: debug : virNetMessageNew:44 : msg=0x7fefbfea7580 tracked=1
>2013-11-25 08:26:02.013+0000: 3913: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfea7580 tx=(nil)
>2013-11-25 08:26:02.013+0000: 3913: debug : virNetServerClientCalculateHandleMode:188 : mode=1
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=1
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventRunDefaultImpl:270 : running default event implementation
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.013+0000: 3917: debug : virNetServerHandleJob:184 : server=0x7fefbfea6430 client=0x7fefbfeb5710 message=0x7fefbfeb52f0 prog=0x7fefbfeafc60
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.013+0000: 3917: debug : virNetServerProgramDispatch:285 : prog=536903814 ver=1 type=0 status=0 serial=6 proc=2
>2013-11-25 08:26:02.013+0000: 3917: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fef94000940
>2013-11-25 08:26:02.013+0000: 3917: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fef94000940
>2013-11-25 08:26:02.013+0000: 3917: debug : remoteDispatchConnectCloseHelper:174 : server=0x7fefbfea6430 client=0x7fefbfeb5710 msg=0x7fefbfeb52f0 rerr=0x7fefadda6c90 args=0x7fef880021e0 ret=0x7fef880020a0
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=0 w=1, f=6 e=1 d=0
>2013-11-25 08:26:02.013+0000: 3917: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fef94000940
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=1 w=2, f=8 e=1 d=0
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=2 w=3, f=11 e=1 d=0
>2013-11-25 08:26:02.013+0000: 3917: debug : virNetMessageEncodePayload:374 : Encode length as 28
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=3 w=4, f=12 e=1 d=0
>2013-11-25 08:26:02.013+0000: 3917: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fef94000940
>2013-11-25 08:26:02.013+0000: 3917: debug : virNetServerClientSendMessageLocked:1451 : msg=0x7fefbfeb52f0 proc=2 len=28 offset=0
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=4 w=5, f=13 e=1 d=0
>2013-11-25 08:26:02.013+0000: 3917: debug : virNetServerClientSendMessageLocked:1459 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x7fefbfeb5710 len=28 prog=536903814 vers=1 proc=2 type=1 status=0 serial=6
>2013-11-25 08:26:02.013+0000: 3917: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfea7580 tx=0x7fefbfeb52f0
>2013-11-25 08:26:02.013+0000: 3917: debug : virNetServerClientCalculateHandleMode:188 : mode=2
>2013-11-25 08:26:02.013+0000: 3917: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=2
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=6 w=7, f=15 e=0 d=0
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=7 w=8, f=15 e=1 d=0
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=8 w=9, f=17 e=1 d=0
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=9 w=11, f=21 e=25 d=0
>2013-11-25 08:26:02.013+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=10 w=14, f=22 e=1 d=0
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 2 timers
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1385367967013
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1385367967013 now=1385367962014
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollCalculateTimeout:361 : Timeout at 1385367967013 due in 4999 ms
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollRunOnce:627 : EVENT_POLL_RUN: nhandles=10 timeout=4999
>2013-11-25 08:26:02.014+0000: 3917: debug : virEventPollInterruptLocked:714 : Interrupting
>2013-11-25 08:26:02.014+0000: 3917: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeafc60
>2013-11-25 08:26:02.014+0000: 3917: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollRunOnce:638 : Poll got 1 event(s)
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchTimeouts:423 : Dispatch 2
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchHandles:468 : Dispatch 10
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchHandles:482 : i=0 w=1
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchHandles:482 : i=1 w=2
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchHandles:482 : i=2 w=3
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchHandles:482 : i=3 w=4
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchHandles:482 : i=4 w=5
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchHandles:482 : i=5 w=6
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchHandles:482 : i=7 w=8
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchHandles:482 : i=8 w=9
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchHandles:482 : i=9 w=11
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchHandles:482 : i=10 w=14
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventRunDefaultImpl:270 : running default event implementation
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=0 w=1, f=6 e=1 d=0
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=1 w=2, f=8 e=1 d=0
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=2 w=3, f=11 e=1 d=0
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=3 w=4, f=12 e=1 d=0
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=4 w=5, f=13 e=1 d=0
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=6 w=7, f=15 e=0 d=0
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=7 w=8, f=15 e=1 d=0
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=8 w=9, f=17 e=1 d=0
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=9 w=11, f=21 e=25 d=0
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=10 w=14, f=22 e=4 d=0
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 2 timers
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollCalculateTimeout:340 : Got a timeout scheduled for 1385367967013
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollCalculateTimeout:353 : Schedule timeout then=1385367967013 now=1385367962014
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollCalculateTimeout:361 : Timeout at 1385367967013 due in 4999 ms
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollRunOnce:627 : EVENT_POLL_RUN: nhandles=10 timeout=4999
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollRunOnce:638 : Poll got 1 event(s)
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchTimeouts:423 : Dispatch 2
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchHandles:468 : Dispatch 10
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchHandles:482 : i=0 w=1
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchHandles:482 : i=1 w=2
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchHandles:482 : i=2 w=3
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchHandles:482 : i=3 w=4
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchHandles:482 : i=4 w=5
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchHandles:482 : i=5 w=6
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchHandles:482 : i=7 w=8
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchHandles:482 : i=8 w=9
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchHandles:482 : i=9 w=11
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchHandles:482 : i=10 w=14
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollDispatchHandles:496 : EVENT_POLL_DISPATCH_HANDLE: watch=14 events=2
>2013-11-25 08:26:02.014+0000: 3913: debug : virNetMessageFree:72 : msg=0x7fefbfeb52f0 nfds=0 cb=(nil)
>2013-11-25 08:26:02.014+0000: 3913: debug : virNetServerClientCalculateHandleMode:153 : tls=(nil) hs=-1, rx=0x7fefbfea7580 tx=(nil)
>2013-11-25 08:26:02.014+0000: 3913: debug : virNetServerClientCalculateHandleMode:188 : mode=0
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=14 events=0
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.014+0000: 3913: debug : virNetServerClientClose:941 : client=0x7fefbfeb5710
>2013-11-25 08:26:02.014+0000: 3913: debug : virKeepAliveStop:307 : RPC_KEEPALIVE_STOP: ka=0x7fefbfeb45a0 client=0x7fefbfeb5710
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollRemoveTimeout:300 : EVENT_POLL_REMOVE_TIMEOUT: timer=6
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 0 140667668162688
>2013-11-25 08:26:02.014+0000: 3913: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.014+0000: 3913: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeb45a0
>2013-11-25 08:26:02.014+0000: 3913: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.014+0000: 3913: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.014+0000: 3913: debug : daemonRemoveAllClientStreams:486 : stream=(nil)
>2013-11-25 08:26:02.014+0000: 3913: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollRemoveHandle:180 : EVENT_POLL_REMOVE_HANDLE: watch=14
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollRemoveHandle:193 : mark delete 10 22
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 0 140667668162688
>2013-11-25 08:26:02.014+0000: 3913: debug : virNetMessageFree:72 : msg=0x7fefbfea7580 nfds=0 cb=(nil)
>2013-11-25 08:26:02.014+0000: 3913: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeb4fd0
>2013-11-25 08:26:02.014+0000: 3913: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventRunDefaultImpl:270 : running default event implementation
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollCleanupTimeouts:514 : Cleanup 2
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollCleanupTimeouts:527 : EVENT_POLL_PURGE_TIMEOUT: timer=6
>2013-11-25 08:26:02.014+0000: 3913: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeb45a0
>2013-11-25 08:26:02.014+0000: 3913: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7fefbfeb45a0
>2013-11-25 08:26:02.014+0000: 3913: debug : virKeepAliveDispose:227 : RPC_KEEPALIVE_DISPOSE: ka=0x7fefbfeb45a0
>2013-11-25 08:26:02.014+0000: 3913: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollCleanupHandles:562 : Cleanup 11
>2013-11-25 08:26:02.014+0000: 3913: debug : virEventPollCleanupHandles:575 : EVENT_POLL_PURGE_HANDLE: watch=14
>2013-11-25 08:26:02.014+0000: 3913: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.014+0000: 3913: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7fefbfeb5710
>2013-11-25 08:26:02.014+0000: 3913: debug : virNetServerClientDispose:905 : RPC_SERVER_CLIENT_DISPOSE: client=0x7fefbfeb5710
>2013-11-25 08:26:02.014+0000: 3913: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fef94000940
>2013-11-25 08:26:02.014+0000: 3913: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7fef94000940
>2013-11-25 08:26:02.015+0000: 3913: debug : virFileClose:90 : Closed fd 23
>2013-11-25 08:26:02.016+0000: 3913: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7fefbfeb56a0 classname=virIdentity
>2013-11-25 08:26:02.016+0000: 3913: debug : virIdentitySetAttr:282 : ident=0x7fefbfeb56a0 attribute=0 value=root
>2013-11-25 08:26:02.016+0000: 3913: debug : virIdentitySetAttr:282 : ident=0x7fefbfeb56a0 attribute=1 value=0
>2013-11-25 08:26:02.016+0000: 3913: debug : virIdentitySetAttr:282 : ident=0x7fefbfeb56a0 attribute=2 value=root
>2013-11-25 08:26:02.016+0000: 3913: debug : virIdentitySetAttr:282 : ident=0x7fefbfeb56a0 attribute=3 value=0
>2013-11-25 08:26:02.016+0000: 3913: debug : virIdentitySetAttr:282 : ident=0x7fefbfeb56a0 attribute=8 value=system_u:system_r:virtd_t:s0-s0:c0.c1023
>2013-11-25 08:26:02.016+0000: 3913: debug : virIdentitySetAttr:282 : ident=0x7fefbfeb56a0 attribute=4 value=3913
>2013-11-25 08:26:02.016+0000: 3913: debug : virIdentitySetAttr:282 : ident=0x7fefbfeb56a0 attribute=5 value=131294
>2013-11-25 08:26:02.016+0000: 3913: debug : virObjectRef:293 : OBJECT_REF: obj=0x7fefbfeb56a0
>2013-11-25 08:26:02.016+0000: 3913: debug : virConnectClose:1523 : conn=0x7fef98001b30
>2013-11-25 08:26:02.016+0000: 3913: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fef98001b30
>2013-11-25 08:26:02.016+0000: 3913: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7fef98001b30
>2013-11-25 08:26:02.016+0000: 3913: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefa000f370
>2013-11-25 08:26:02.016+0000: 3913: debug : virCloseCallbacksRun:321 : conn=0x7fef98001b30
>2013-11-25 08:26:02.016+0000: 3913: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fef98001580
>2013-11-25 08:26:02.016+0000: 3913: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7fef98001580
>2013-11-25 08:26:02.016+0000: 3913: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeb56a0
>2013-11-25 08:26:02.016+0000: 3913: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeb56a0
>2013-11-25 08:26:02.016+0000: 3913: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7fefbfeb56a0
>2013-11-25 08:26:02.016+0000: 3913: debug : virEventPollRemoveTimeout:300 : EVENT_POLL_REMOVE_TIMEOUT: timer=5
>2013-11-25 08:26:02.016+0000: 3913: debug : virEventPollInterruptLocked:710 : Skip interrupt, 1 140667668162688
>2013-11-25 08:26:02.016+0000: 3913: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7fefbfeb4fd0
>2013-11-25 08:26:02.016+0000: 3913: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7fefbfeb4fd0
>2013-11-25 08:26:02.016+0000: 3913: debug : virNetSocketDispose:1002 : RPC_SOCKET_DISPOSE: sock=0x7fefbfeb4fd0
>2013-11-25 08:26:02.016+0000: 3913: debug : virEventPollRemoveHandle:180 : EVENT_POLL_REMOVE_HANDLE: watch=14
>2013-11-25 08:26:02.016+0000: 3913: debug : virFileClose:90 : Closed fd 22
>2013-11-25 08:26:02.016+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=0 w=1, f=6 e=1 d=0
>2013-11-25 08:26:02.016+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=1 w=2, f=8 e=1 d=0
>2013-11-25 08:26:02.016+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=2 w=3, f=11 e=1 d=0
>2013-11-25 08:26:02.016+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=3 w=4, f=12 e=1 d=0
>2013-11-25 08:26:02.016+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=4 w=5, f=13 e=1 d=0
>2013-11-25 08:26:02.016+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-11-25 08:26:02.016+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=6 w=7, f=15 e=0 d=0
>2013-11-25 08:26:02.016+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=7 w=8, f=15 e=1 d=0
>2013-11-25 08:26:02.016+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=8 w=9, f=17 e=1 d=0
>2013-11-25 08:26:02.016+0000: 3913: debug : virEventPollMakePollFDs:391 : Prepare n=9 w=11, f=21 e=25 d=0
>2013-11-25 08:26:02.016+0000: 3913: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 1 timers
>2013-11-25 08:26:02.016+0000: 3913: debug : virEventPollCalculateTimeout:361 : Timeout at 0 due in -1 ms
>2013-11-25 08:26:02.016+0000: 3913: debug : virEventPollRunOnce:627 : EVENT_POLL_RUN: nhandles=9 timeout=-1

Actions: View

Attachments on bug 1025108: 828554