Attachment 916000 Details for Bug 1116754 – libvirt debug log

libvirt debug log

log.xml (text/xml), 64.25 KB, created by Luyao Huang on 2014-07-07 08:50:49 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Luyao Huang

Created: 2014-07-07 08:50:49 UTC

Size: 64.25 KB

patch

obsolete

>2014-07-07 07:43:34.122+0000: 2888: error : networkCheckRouteCollision:2187 : internal error Network is already in use by interface virbr13
>Caught Segmentation violation dumping internal log buffer:
>
>
>    ====== start of log =====
>
>EventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-07 07:43:26.237+00002885: debug : virEventPollCleanupTimeouts:514 : EVENT_POLL_PURGE_TIMEOUT: timer=8
>2014-07-07 07:43:26.237+00002885: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x176add0
>2014-07-07 07:43:26.237+00002885: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x176add0
>2014-07-07 07:43:26.237+00002885: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x176cca0
>2014-07-07 07:43:26.237+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:26.237+00002885: debug : virEventPollCleanupHandles:562 : EVENT_POLL_PURGE_HANDLE: watch=11
>2014-07-07 07:43:26.237+00002885: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x176cca0
>2014-07-07 07:43:26.237+00002885: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x176cca0
>2014-07-07 07:43:26.238+00002885: debug : virConnectClose:1449 : conn=0x7fedac001d70
>2014-07-07 07:43:26.238+00002885: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fedac001d70
>2014-07-07 07:43:26.238+00002885: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7fedac001d70
>2014-07-07 07:43:26.240+00002885: debug : qemuDriverCloseCallbackRunAll:785 : conn=0x7fedac001d70
>2014-07-07 07:43:26.240+00002885: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fedac001670
>2014-07-07 07:43:26.240+00002885: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7fedac001670
>2014-07-07 07:43:26.240+00002885: debug : virEventPollRemoveTimeout:288 : EVENT_POLL_REMOVE_TIMEOUT: timer=7
>2014-07-07 07:43:26.240+00002885: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -892856224
>2014-07-07 07:43:26.240+00002885: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x176ca60
>2014-07-07 07:43:26.240+00002885: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x176ca60
>2014-07-07 07:43:26.240+00002885: debug : virNetSocketDispose:889 : sock=0x176ca60 fd=18
>2014-07-07 07:43:26.240+00002885: debug : virEventPollRemoveHandle:175 : EVENT_POLL_REMOVE_HANDLE: watch=11
>2014-07-07 07:43:26.240+00002885: debug : virFileClose:72 : Closed fd 18
>2014-07-07 07:43:26.240+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-07 07:43:26.240+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-07 07:43:26.240+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-07 07:43:26.240+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-07 07:43:26.240+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-07 07:43:26.240+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-07-07 07:43:26.240+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-07 07:43:26.240+00002885: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2014-07-07 07:43:26.240+00002885: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2014-07-07 07:43:26.240+00002885: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=7 timeout=-1
>2014-07-07 07:43:34.088+00002885: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-07 07:43:34.088+00002885: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2014-07-07 07:43:34.088+00002885: debug : virEventPollDispatchHandles:455 : Dispatch 7
>2014-07-07 07:43:34.088+00002885: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-07 07:43:34.088+00002885: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-07 07:43:34.088+00002885: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-07 07:43:34.088+00002885: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=3 events=1
>2014-07-07 07:43:34.088+00002885: debug : virNetSocketNew:147 : localAddr=0x7fffa671d0d0 remoteAddr=0x7fffa671d040 fd=18 errfd=-1 pid=0
>2014-07-07 07:43:34.088+00002885: debug : virObjectNew:110 : OBJECT_NEW: obj=0x176c730 classname=virNetSocket
>2014-07-07 07:43:34.088+00002885: debug : virNetSocketNew:204 : RPC_SOCKET_NEW: sock=0x176c730 fd=18 errfd=-1 pid=0 localAddr=127.0.0.1;0, remoteAddr=127.0.0.1;0
>2014-07-07 07:43:34.088+00002885: debug : virNetServerClientNew:403 : sock=0x176c730 auth=2 tls=(nil)
>2014-07-07 07:43:34.088+00002885: debug : virObjectNew:110 : OBJECT_NEW: obj=0x1769db0 classname=virNetServerClient
>2014-07-07 07:43:34.088+00002885: debug : virObjectRef:168 : OBJECT_REF: obj=0x176c730
>2014-07-07 07:43:34.088+00002885: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -892856224
>2014-07-07 07:43:34.088+00002885: debug : virEventPollAddTimeout:243 : EVENT_POLL_ADD_TIMEOUT: timer=9 frequency=-1 cb=0x3da3946f50 opaque=0x1769db0 ff=(nil)
>2014-07-07 07:43:34.088+00002885: debug : virNetMessageNew:45 : msg=0x1769e90 tracked=1
>2014-07-07 07:43:34.088+00002885: debug : virNetServerClientNewInternal:382 : RPC_SERVER_CLIENT_NEW: client=0x1769db0 sock=0x176c730
>2014-07-07 07:43:34.088+00002885: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1769e90 tx=(nil)
>2014-07-07 07:43:34.088+00002885: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-07 07:43:34.088+00002885: debug : virObjectRef:168 : OBJECT_REF: obj=0x1769db0
>2014-07-07 07:43:34.088+00002885: debug : virNetServerClientRegisterEvent:199 : Registering client event callback 1
>2014-07-07 07:43:34.088+00002885: debug : virObjectRef:168 : OBJECT_REF: obj=0x176c730
>2014-07-07 07:43:34.088+00002885: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -892856224
>2014-07-07 07:43:34.088+00002885: debug : virEventPollAddHandle:136 : EVENT_POLL_ADD_HANDLE: watch=12 fd=18 events=1 cb=0x3da394ac70 opaque=0x176c730 ff=0x3da394b060
>2014-07-07 07:43:34.088+00002885: debug : virObjectRef:168 : OBJECT_REF: obj=0x1769db0
>2014-07-07 07:43:34.088+00002885: debug : virKeepAliveNew:212 : client=0x1769db0, interval=5, count=5
>2014-07-07 07:43:34.088+00002885: debug : virObjectNew:110 : OBJECT_NEW: obj=0x176a3e0 classname=virKeepAlive
>2014-07-07 07:43:34.088+00002885: debug : virKeepAliveNew:236 : RPC_KEEPALIVE_NEW: ka=0x176a3e0 client=0x1769db0
>2014-07-07 07:43:34.088+00002885: debug : virObjectRef:168 : OBJECT_REF: obj=0x1769db0
>2014-07-07 07:43:34.088+00002885: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1769db0
>2014-07-07 07:43:34.088+00002885: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x176c730
>2014-07-07 07:43:34.088+00002885: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-07 07:43:34.088+00002885: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-07 07:43:34.088+00002885: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-07 07:43:34.088+00002885: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-07 07:43:34.088+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-07 07:43:34.088+00002885: debug : virEventPollCleanupTimeouts:514 : EVENT_POLL_PURGE_TIMEOUT: timer=7
>2014-07-07 07:43:34.088+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.088+00002885: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-07 07:43:34.088+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-07-07 07:43:34.088+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.088+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-07 07:43:34.088+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-07 07:43:34.088+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-07 07:43:34.088+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-07 07:43:34.088+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-07 07:43:34.088+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-07-07 07:43:34.088+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-07 07:43:34.088+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=18 e=1 d=0
>2014-07-07 07:43:34.088+00002885: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2014-07-07 07:43:34.088+00002885: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2014-07-07 07:43:34.088+00002885: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=-1
>2014-07-07 07:43:34.089+00002885: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-07 07:43:34.089+00002885: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2014-07-07 07:43:34.089+00002885: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2014-07-07 07:43:34.089+00002885: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-07 07:43:34.089+00002885: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-07 07:43:34.089+00002885: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-07 07:43:34.089+00002885: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-07 07:43:34.089+00002885: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-07 07:43:34.089+00002885: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-07 07:43:34.089+00002885: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-07 07:43:34.089+00002885: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2014-07-07 07:43:34.089+00002885: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1
>2014-07-07 07:43:34.089+00002885: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2014-07-07 07:43:34.089+00002885: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1769e90 tx=(nil)
>2014-07-07 07:43:34.089+00002885: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-07 07:43:34.089+00002885: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2014-07-07 07:43:34.089+00002885: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -892856224
>2014-07-07 07:43:34.089+00002885: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x1769db0 len=28 prog=536903814 vers=1 proc=66 type=0 status=0 serial=0
>2014-07-07 07:43:34.089+00002885: debug : virKeepAliveCheckMessage:384 : ka=0x176a3e0, client=0x1769db0, msg=0x1769e90
>2014-07-07 07:43:34.089+00002885: debug : virObjectRef:168 : OBJECT_REF: obj=0x1769db0
>2014-07-07 07:43:34.089+00002885: debug : virNetServerDispatchNewMessage:218 : server=0x17649c0 client=0x1769db0 message=0x1769e90
>2014-07-07 07:43:34.089+00002885: debug : virObjectRef:168 : OBJECT_REF: obj=0x176f020
>2014-07-07 07:43:34.089+00002885: debug : virNetMessageNew:45 : msg=0x176d460 tracked=1
>2014-07-07 07:43:34.089+00002885: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x176d460 tx=(nil)
>2014-07-07 07:43:34.089+00002889: debug : virNetServerHandleJob:189 : server=0x17649c0 client=0x1769db0 message=0x1769e90 prog=0x176f020
>2014-07-07 07:43:34.089+00002885: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-07 07:43:34.089+00002889: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=0 proc=66
>2014-07-07 07:43:34.089+00002885: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2014-07-07 07:43:34.089+00002885: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -892856224
>2014-07-07 07:43:34.089+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-07-07 07:43:34.089+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.089+00002885: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-07 07:43:34.089+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-07-07 07:43:34.089+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.089+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-07 07:43:34.089+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-07 07:43:34.089+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-07 07:43:34.089+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-07 07:43:34.089+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-07 07:43:34.089+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-07-07 07:43:34.089+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-07 07:43:34.089+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=18 e=1 d=0
>2014-07-07 07:43:34.089+00002885: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2014-07-07 07:43:34.089+00002885: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2014-07-07 07:43:34.089+00002885: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=-1
>2014-07-07 07:43:34.089+00002889: debug : remoteDispatchAuthListHelper:18 : server=0x17649c0 client=0x1769db0 msg=0x1769e90 rerr=0x7fedc2bcab80 args=0x7feda80008c0 ret=0x7feda80008e0
>2014-07-07 07:43:34.089+00002889: debug : virFileClose:72 : Closed fd 19
>2014-07-07 07:43:34.089+00002889: info : remoteDispatchAuthList:2432 : Bypass polkit auth for privileged client pid:3165,uid:0
>2014-07-07 07:43:34.089+00002889: debug : virNetMessageEncodePayload:358 : Encode length as 36
>2014-07-07 07:43:34.089+00002889: debug : virNetServerClientSendMessageLocked:1122 : msg=0x1769e90 proc=66 len=36 offset=0
>2014-07-07 07:43:34.089+00002889: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1769db0 len=36 prog=536903814 vers=1 proc=66 type=1 status=0 serial=0
>2014-07-07 07:43:34.089+00002889: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x176d460 tx=0x1769e90
>2014-07-07 07:43:34.089+00002889: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-07-07 07:43:34.089+00002889: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=3
>2014-07-07 07:43:34.089+00002889: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-07-07 07:43:34.089+00002889: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x176f020
>2014-07-07 07:43:34.089+00002885: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-07 07:43:34.089+00002885: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2014-07-07 07:43:34.089+00002885: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2014-07-07 07:43:34.089+00002885: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-07 07:43:34.089+00002885: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-07-07 07:43:34.089+00002885: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-07 07:43:34.090+00002885: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-07 07:43:34.090+00002885: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-07 07:43:34.090+00002885: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-07 07:43:34.090+00002885: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-07 07:43:34.090+00002885: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-07 07:43:34.090+00002885: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2014-07-07 07:43:34.090+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-07-07 07:43:34.090+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.090+00002885: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-07 07:43:34.090+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-07-07 07:43:34.090+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.090+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-07 07:43:34.090+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-07 07:43:34.090+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-07 07:43:34.090+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-07 07:43:34.090+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-07 07:43:34.090+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-07-07 07:43:34.090+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-07 07:43:34.090+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=18 e=5 d=0
>2014-07-07 07:43:34.090+00002885: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2014-07-07 07:43:34.090+00002885: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2014-07-07 07:43:34.090+00002885: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=-1
>2014-07-07 07:43:34.090+00002885: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-07 07:43:34.090+00002885: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2014-07-07 07:43:34.089+00002889: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1769db0
>2014-07-07 07:43:34.090+00002885: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2014-07-07 07:43:34.090+00002885: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-07 07:43:34.090+00002885: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-07 07:43:34.090+00002885: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-07 07:43:34.090+00002885: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-07 07:43:34.090+00002885: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-07 07:43:34.090+00002885: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-07 07:43:34.090+00002885: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-07 07:43:34.090+00002885: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2014-07-07 07:43:34.090+00002885: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2
>2014-07-07 07:43:34.090+00002885: debug : virNetMessageFree:73 : msg=0x1769e90 nfds=0 cb=(nil)
>2014-07-07 07:43:34.090+00002885: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x176d460 tx=(nil)
>2014-07-07 07:43:34.090+00002885: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-07 07:43:34.090+00002885: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2014-07-07 07:43:34.090+00002885: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -892856224
>2014-07-07 07:43:34.090+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-07-07 07:43:34.090+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.090+00002885: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-07 07:43:34.090+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-07-07 07:43:34.090+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.090+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-07 07:43:34.090+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-07 07:43:34.090+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-07 07:43:34.090+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-07 07:43:34.090+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-07 07:43:34.090+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-07-07 07:43:34.090+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-07 07:43:34.090+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=18 e=1 d=0
>2014-07-07 07:43:34.090+00002885: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2014-07-07 07:43:34.090+00002885: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2014-07-07 07:43:34.090+00002885: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=-1
>2014-07-07 07:43:34.091+00002885: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-07 07:43:34.091+00002885: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2014-07-07 07:43:34.091+00002885: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2014-07-07 07:43:34.091+00002885: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-07 07:43:34.091+00002885: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-07 07:43:34.091+00002885: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-07 07:43:34.091+00002885: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-07 07:43:34.091+00002885: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-07 07:43:34.091+00002885: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-07 07:43:34.091+00002885: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-07 07:43:34.091+00002885: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2014-07-07 07:43:34.091+00002885: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1
>2014-07-07 07:43:34.091+00002885: debug : virNetMessageDecodeLength:152 : Got length, now need 32 total (28 more)
>2014-07-07 07:43:34.091+00002885: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x176d460 tx=(nil)
>2014-07-07 07:43:34.091+00002885: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-07 07:43:34.091+00002885: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2014-07-07 07:43:34.091+00002885: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -892856224
>2014-07-07 07:43:34.091+00002885: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x1769db0 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=1
>2014-07-07 07:43:34.091+00002885: debug : virKeepAliveCheckMessage:384 : ka=0x176a3e0, client=0x1769db0, msg=0x176d460
>2014-07-07 07:43:34.091+00002885: debug : virObjectRef:168 : OBJECT_REF: obj=0x1769db0
>2014-07-07 07:43:34.091+00002885: debug : virNetServerDispatchNewMessage:218 : server=0x17649c0 client=0x1769db0 message=0x176d460
>2014-07-07 07:43:34.091+00002885: debug : virObjectRef:168 : OBJECT_REF: obj=0x176f020
>2014-07-07 07:43:34.091+00002885: debug : virNetMessageNew:45 : msg=0x176c220 tracked=1
>2014-07-07 07:43:34.091+00002885: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x176c220 tx=(nil)
>2014-07-07 07:43:34.091+00002885: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-07 07:43:34.091+00002885: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2014-07-07 07:43:34.091+00002885: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -892856224
>2014-07-07 07:43:34.091+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-07-07 07:43:34.091+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.091+00002885: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-07 07:43:34.091+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2014-07-07 07:43:34.091+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.091+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-07 07:43:34.091+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-07 07:43:34.091+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-07 07:43:34.091+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-07 07:43:34.091+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-07 07:43:34.091+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-07-07 07:43:34.091+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-07 07:43:34.091+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=18 e=1 d=0
>2014-07-07 07:43:34.091+00002885: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2014-07-07 07:43:34.091+00002885: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2014-07-07 07:43:34.091+00002885: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=-1
>2014-07-07 07:43:34.091+00002892: debug : virNetServerHandleJob:189 : server=0x17649c0 client=0x1769db0 message=0x176d460 prog=0x176f020
>2014-07-07 07:43:34.091+00002892: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=1 proc=60
>2014-07-07 07:43:34.091+00002892: debug : remoteDispatchSupportsFeatureHelper:13350 : server=0x17649c0 client=0x1769db0 msg=0x176d460 rerr=0x7fedc0dc7b80 args=0x7fed900008c0 ret=0x7fed900008e0
>2014-07-07 07:43:34.091+00002892: debug : virKeepAliveStart:289 : RPC_KEEPALIVE_START: ka=0x176a3e0 client=0x1769db0 interval=0 count=0
>2014-07-07 07:43:34.091+00002892: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-07-07 07:43:34.091+00002892: debug : virEventPollAddTimeout:243 : EVENT_POLL_ADD_TIMEOUT: timer=10 frequency=5000 cb=0x3da394a8e0 opaque=0x176a3e0 ff=0x41f1b0
>2014-07-07 07:43:34.091+00002885: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-07 07:43:34.091+00002885: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-07 07:43:34.091+00002885: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2014-07-07 07:43:34.091+00002885: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-07 07:43:34.091+00002885: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-07-07 07:43:34.091+00002885: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-07 07:43:34.091+00002885: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-07 07:43:34.091+00002885: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-07 07:43:34.091+00002885: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-07 07:43:34.091+00002885: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-07 07:43:34.091+00002885: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-07 07:43:34.091+00002885: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2014-07-07 07:43:34.091+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-07 07:43:34.091+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.091+00002892: debug : virObjectRef:168 : OBJECT_REF: obj=0x176a3e0
>2014-07-07 07:43:34.092+00002892: debug : virNetMessageEncodePayload:358 : Encode length as 32
>2014-07-07 07:43:34.092+00002892: debug : virNetServerClientSendMessageLocked:1122 : msg=0x176d460 proc=60 len=32 offset=0
>2014-07-07 07:43:34.092+00002892: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1769db0 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=1
>2014-07-07 07:43:34.092+00002892: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x176c220 tx=0x176d460
>2014-07-07 07:43:34.092+00002892: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-07-07 07:43:34.092+00002892: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=3
>2014-07-07 07:43:34.092+00002892: debug : virEventPollInterruptLocked:697 : Skip interrupt, 0 -892856224
>2014-07-07 07:43:34.092+00002892: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x176f020
>2014-07-07 07:43:34.092+00002892: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1769db0
>2014-07-07 07:43:34.092+00002885: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-07 07:43:34.092+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-07 07:43:34.092+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.092+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-07 07:43:34.092+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-07 07:43:34.092+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-07 07:43:34.092+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-07 07:43:34.092+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-07 07:43:34.092+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-07-07 07:43:34.092+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-07 07:43:34.092+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=18 e=5 d=0
>2014-07-07 07:43:34.092+00002885: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-07 07:43:34.092+00002885: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1404719019091
>2014-07-07 07:43:34.092+00002885: debug : virEventPollCalculateTimeout:346 : Timeout at 1404719019091 due in 4999 ms
>2014-07-07 07:43:34.092+00002885: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=4999
>2014-07-07 07:43:34.092+00002885: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-07 07:43:34.092+00002885: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-07 07:43:34.092+00002885: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2014-07-07 07:43:34.092+00002885: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-07 07:43:34.092+00002885: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-07 07:43:34.092+00002885: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-07 07:43:34.092+00002885: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-07 07:43:34.092+00002885: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-07 07:43:34.092+00002885: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-07 07:43:34.092+00002885: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-07 07:43:34.092+00002885: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2014-07-07 07:43:34.092+00002885: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2
>2014-07-07 07:43:34.092+00002885: debug : virNetMessageFree:73 : msg=0x176d460 nfds=0 cb=(nil)
>2014-07-07 07:43:34.092+00002885: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x176c220 tx=(nil)
>2014-07-07 07:43:34.092+00002885: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-07 07:43:34.092+00002885: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2014-07-07 07:43:34.092+00002885: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -892856224
>2014-07-07 07:43:34.092+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-07 07:43:34.092+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.092+00002885: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-07 07:43:34.092+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-07 07:43:34.092+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.092+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-07 07:43:34.092+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-07 07:43:34.092+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-07 07:43:34.092+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-07 07:43:34.092+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-07 07:43:34.092+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-07-07 07:43:34.092+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-07 07:43:34.092+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=18 e=1 d=0
>2014-07-07 07:43:34.092+00002885: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-07 07:43:34.092+00002885: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1404719019091
>2014-07-07 07:43:34.092+00002885: debug : virEventPollCalculateTimeout:346 : Timeout at 1404719019091 due in 4999 ms
>2014-07-07 07:43:34.092+00002885: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=4999
>2014-07-07 07:43:34.093+00002885: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-07 07:43:34.093+00002885: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-07 07:43:34.093+00002885: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2014-07-07 07:43:34.093+00002885: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-07 07:43:34.093+00002885: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-07 07:43:34.093+00002885: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-07 07:43:34.093+00002885: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-07 07:43:34.093+00002885: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-07 07:43:34.093+00002885: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-07 07:43:34.093+00002885: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-07 07:43:34.093+00002885: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2014-07-07 07:43:34.093+00002885: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1
>2014-07-07 07:43:34.093+00002885: debug : virNetMessageDecodeLength:152 : Got length, now need 40 total (36 more)
>2014-07-07 07:43:34.093+00002885: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x176c220 tx=(nil)
>2014-07-07 07:43:34.093+00002885: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-07 07:43:34.093+00002885: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2014-07-07 07:43:34.093+00002885: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -892856224
>2014-07-07 07:43:34.093+00002885: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x1769db0 len=40 prog=536903814 vers=1 proc=1 type=0 status=0 serial=2
>2014-07-07 07:43:34.093+00002885: debug : virKeepAliveCheckMessage:384 : ka=0x176a3e0, client=0x1769db0, msg=0x176c220
>2014-07-07 07:43:34.093+00002885: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=10 frequency=5000
>2014-07-07 07:43:34.093+00002885: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -892856224
>2014-07-07 07:43:34.093+00002885: debug : virObjectRef:168 : OBJECT_REF: obj=0x1769db0
>2014-07-07 07:43:34.093+00002885: debug : virNetServerDispatchNewMessage:218 : server=0x17649c0 client=0x1769db0 message=0x176c220
>2014-07-07 07:43:34.093+00002885: debug : virObjectRef:168 : OBJECT_REF: obj=0x176f020
>2014-07-07 07:43:34.093+00002886: debug : virNetServerHandleJob:189 : server=0x17649c0 client=0x1769db0 message=0x176c220 prog=0x176f020
>2014-07-07 07:43:34.093+00002886: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=2 proc=1
>2014-07-07 07:43:34.093+00002885: debug : virNetMessageNew:45 : msg=0x1769f70 tracked=1
>2014-07-07 07:43:34.093+00002885: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1769f70 tx=(nil)
>2014-07-07 07:43:34.093+00002885: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-07 07:43:34.093+00002885: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2014-07-07 07:43:34.093+00002885: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -892856224
>2014-07-07 07:43:34.093+00002886: debug : remoteDispatchOpenHelper:11127 : server=0x17649c0 client=0x1769db0 msg=0x176c220 rerr=0x7fedc49cdb80 args=0x7fedb80bc130 ret=0x7fedb8011ec0
>2014-07-07 07:43:34.093+00002886: debug : remoteDispatchOpen:766 : priv=0x176a050 conn=(nil)
>2014-07-07 07:43:34.093+00002886: debug : virConnectOpen:1331 : name=
>2014-07-07 07:43:34.093+00002886: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7fedb8019a00 classname=virConnect
>2014-07-07 07:43:34.093+00002886: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7fedb806b140 classname=virConnectCloseCallbackData
>2014-07-07 07:43:34.093+00002886: debug : virConnectGetConfigFile:953 : Loading config file '/etc/libvirt/libvirt.conf'
>2014-07-07 07:43:34.093+00002886: debug : virFileClose:72 : Closed fd 19
>2014-07-07 07:43:34.093+00002886: debug : do_open:1131 : no name, allowing driver auto-select
>2014-07-07 07:43:34.093+00002886: debug : do_open:1174 : trying driver 0 (Test) ...
>2014-07-07 07:43:34.093+00002886: debug : do_open:1180 : driver 0 Test returned DECLINED
>2014-07-07 07:43:34.093+00002886: debug : do_open:1174 : trying driver 1 (ESX) ...
>2014-07-07 07:43:34.093+00002886: debug : do_open:1180 : driver 1 ESX returned DECLINED
>2014-07-07 07:43:34.093+00002886: debug : do_open:1174 : trying driver 2 (remote) ...
>2014-07-07 07:43:34.093+00002886: debug : do_open:1180 : driver 2 remote returned DECLINED
>2014-07-07 07:43:34.093+00002886: debug : do_open:1174 : trying driver 3 (QEMU) ...
>2014-07-07 07:43:34.093+00002886: debug : do_open:1180 : driver 3 QEMU returned SUCCESS
>2014-07-07 07:43:34.093+00002886: debug : do_open:1202 : network driver 0 Test returned DECLINED
>2014-07-07 07:43:34.093+00002886: debug : do_open:1202 : network driver 1 ESX returned DECLINED
>2014-07-07 07:43:34.093+00002886: debug : do_open:1202 : network driver 2 remote returned DECLINED
>2014-07-07 07:43:34.093+00002886: debug : do_open:1202 : network driver 3 Network returned SUCCESS
>2014-07-07 07:43:34.093+00002886: debug : do_open:1217 : interface driver 0 Test returned DECLINED
>2014-07-07 07:43:34.093+00002886: debug : do_open:1217 : interface driver 1 ESX returned DECLINED
>2014-07-07 07:43:34.093+00002886: debug : do_open:1217 : interface driver 2 remote returned DECLINED
>2014-07-07 07:43:34.095+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-07 07:43:34.095+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.095+00002885: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-07 07:43:34.095+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-07 07:43:34.095+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.095+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-07 07:43:34.095+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-07 07:43:34.095+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-07 07:43:34.095+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-07 07:43:34.095+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-07 07:43:34.095+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-07-07 07:43:34.095+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-07 07:43:34.095+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=18 e=1 d=0
>2014-07-07 07:43:34.095+00002885: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-07 07:43:34.095+00002885: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1404719019093
>2014-07-07 07:43:34.095+00002885: debug : virEventPollCalculateTimeout:346 : Timeout at 1404719019093 due in 4998 ms
>2014-07-07 07:43:34.095+00002885: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=4998
>2014-07-07 07:43:34.105+00002886: debug : do_open:1217 : interface driver 3 Interface returned SUCCESS
>2014-07-07 07:43:34.105+00002886: debug : do_open:1233 : storage driver 0 Test returned DECLINED
>2014-07-07 07:43:34.105+00002886: debug : do_open:1233 : storage driver 1 ESX returned DECLINED
>2014-07-07 07:43:34.105+00002886: debug : do_open:1233 : storage driver 2 remote returned DECLINED
>2014-07-07 07:43:34.105+00002886: debug : do_open:1233 : storage driver 3 storage returned SUCCESS
>2014-07-07 07:43:34.105+00002886: debug : do_open:1249 : node driver 0 Test returned DECLINED
>2014-07-07 07:43:34.105+00002886: debug : do_open:1249 : node driver 1 ESX returned DECLINED
>2014-07-07 07:43:34.105+00002886: debug : do_open:1249 : node driver 2 remote returned DECLINED
>2014-07-07 07:43:34.105+00002886: debug : do_open:1249 : node driver 3 udevDeviceMonitor returned SUCCESS
>2014-07-07 07:43:34.105+00002886: debug : do_open:1265 : secret driver 0 Test returned DECLINED
>2014-07-07 07:43:34.105+00002886: debug : do_open:1265 : secret driver 1 ESX returned DECLINED
>2014-07-07 07:43:34.105+00002886: debug : do_open:1265 : secret driver 2 remote returned DECLINED
>2014-07-07 07:43:34.105+00002886: debug : do_open:1265 : secret driver 3 secret returned SUCCESS
>2014-07-07 07:43:34.105+00002886: debug : do_open:1281 : nwfilter driver 0 Test returned DECLINED
>2014-07-07 07:43:34.105+00002886: debug : do_open:1281 : nwfilter driver 1 ESX returned DECLINED
>2014-07-07 07:43:34.105+00002886: debug : do_open:1281 : nwfilter driver 2 remote returned DECLINED
>2014-07-07 07:43:34.105+00002886: debug : do_open:1281 : nwfilter driver 3 nwfilter returned SUCCESS
>2014-07-07 07:43:34.105+00002886: debug : virNetMessageEncodePayload:358 : Encode length as 28
>2014-07-07 07:43:34.105+00002886: debug : virNetServerClientSendMessageLocked:1122 : msg=0x176c220 proc=1 len=28 offset=0
>2014-07-07 07:43:34.106+00002886: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1769db0 len=28 prog=536903814 vers=1 proc=1 type=1 status=0 serial=2
>2014-07-07 07:43:34.106+00002886: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1769f70 tx=0x176c220
>2014-07-07 07:43:34.106+00002886: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-07-07 07:43:34.106+00002886: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=3
>2014-07-07 07:43:34.106+00002886: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-07-07 07:43:34.106+00002886: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x176f020
>2014-07-07 07:43:34.106+00002886: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1769db0
>2014-07-07 07:43:34.106+00002885: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-07 07:43:34.106+00002885: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-07 07:43:34.106+00002885: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2014-07-07 07:43:34.106+00002885: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-07 07:43:34.106+00002885: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-07-07 07:43:34.106+00002885: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-07 07:43:34.106+00002885: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-07 07:43:34.106+00002885: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-07 07:43:34.106+00002885: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-07 07:43:34.106+00002885: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-07 07:43:34.106+00002885: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-07 07:43:34.106+00002885: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2014-07-07 07:43:34.106+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-07 07:43:34.106+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.106+00002885: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-07 07:43:34.106+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-07 07:43:34.106+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.106+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-07 07:43:34.106+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-07 07:43:34.106+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-07 07:43:34.106+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-07 07:43:34.106+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-07 07:43:34.106+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-07-07 07:43:34.106+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-07 07:43:34.106+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=18 e=5 d=0
>2014-07-07 07:43:34.106+00002885: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-07 07:43:34.106+00002885: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1404719019093
>2014-07-07 07:43:34.106+00002885: debug : virEventPollCalculateTimeout:346 : Timeout at 1404719019093 due in 4987 ms
>2014-07-07 07:43:34.106+00002885: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=4987
>2014-07-07 07:43:34.106+00002885: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-07 07:43:34.106+00002885: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-07 07:43:34.106+00002885: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2014-07-07 07:43:34.106+00002885: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-07 07:43:34.106+00002885: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-07 07:43:34.106+00002885: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-07 07:43:34.106+00002885: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-07 07:43:34.106+00002885: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-07 07:43:34.106+00002885: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-07 07:43:34.106+00002885: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-07 07:43:34.106+00002885: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2014-07-07 07:43:34.106+00002885: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2
>2014-07-07 07:43:34.106+00002885: debug : virNetMessageFree:73 : msg=0x176c220 nfds=0 cb=(nil)
>2014-07-07 07:43:34.106+00002885: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1769f70 tx=(nil)
>2014-07-07 07:43:34.106+00002885: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-07 07:43:34.106+00002885: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2014-07-07 07:43:34.106+00002885: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -892856224
>2014-07-07 07:43:34.106+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-07 07:43:34.106+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.106+00002885: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-07 07:43:34.106+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-07 07:43:34.106+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.106+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-07 07:43:34.106+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-07 07:43:34.106+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-07 07:43:34.106+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-07 07:43:34.106+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-07 07:43:34.106+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-07-07 07:43:34.106+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-07 07:43:34.106+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=18 e=1 d=0
>2014-07-07 07:43:34.106+00002885: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-07 07:43:34.106+00002885: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1404719019093
>2014-07-07 07:43:34.106+00002885: debug : virEventPollCalculateTimeout:346 : Timeout at 1404719019093 due in 4987 ms
>2014-07-07 07:43:34.106+00002885: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=4987
>2014-07-07 07:43:34.107+00002885: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-07 07:43:34.107+00002885: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-07 07:43:34.107+00002885: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2014-07-07 07:43:34.107+00002885: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-07 07:43:34.107+00002885: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-07 07:43:34.107+00002885: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-07 07:43:34.107+00002885: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-07 07:43:34.107+00002885: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-07 07:43:34.107+00002885: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-07 07:43:34.107+00002885: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-07 07:43:34.107+00002885: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2014-07-07 07:43:34.107+00002885: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1
>2014-07-07 07:43:34.107+00002885: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2014-07-07 07:43:34.107+00002885: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1769f70 tx=(nil)
>2014-07-07 07:43:34.107+00002885: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-07 07:43:34.107+00002885: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2014-07-07 07:43:34.107+00002885: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -892856224
>2014-07-07 07:43:34.107+00002885: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x1769db0 len=28 prog=536903814 vers=1 proc=110 type=0 status=0 serial=3
>2014-07-07 07:43:34.107+00002885: debug : virKeepAliveCheckMessage:384 : ka=0x176a3e0, client=0x1769db0, msg=0x1769f70
>2014-07-07 07:43:34.107+00002885: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=10 frequency=5000
>2014-07-07 07:43:34.107+00002885: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -892856224
>2014-07-07 07:43:34.107+00002885: debug : virObjectRef:168 : OBJECT_REF: obj=0x1769db0
>2014-07-07 07:43:34.107+00002885: debug : virNetServerDispatchNewMessage:218 : server=0x17649c0 client=0x1769db0 message=0x1769f70
>2014-07-07 07:43:34.107+00002885: debug : virObjectRef:168 : OBJECT_REF: obj=0x176f020
>2014-07-07 07:43:34.107+00002887: debug : virNetServerHandleJob:189 : server=0x17649c0 client=0x1769db0 message=0x1769f70 prog=0x176f020
>2014-07-07 07:43:34.107+00002887: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=3 proc=110
>2014-07-07 07:43:34.107+00002885: debug : virNetMessageNew:45 : msg=0x176cc30 tracked=1
>2014-07-07 07:43:34.107+00002885: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x176cc30 tx=(nil)
>2014-07-07 07:43:34.107+00002885: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-07 07:43:34.107+00002885: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2014-07-07 07:43:34.107+00002885: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -892856224
>2014-07-07 07:43:34.107+00002887: debug : remoteDispatchGetURIHelper:7297 : server=0x17649c0 client=0x1769db0 msg=0x1769f70 rerr=0x7fedc3fccb80 args=0x7fedb0000e30 ret=0x7fedb00010b0
>2014-07-07 07:43:34.107+00002887: debug : virConnectGetURI:1700 : conn=0x7fedb8019a00
>2014-07-07 07:43:34.107+00002887: debug : virNetMessageEncodePayload:358 : Encode length as 48
>2014-07-07 07:43:34.107+00002887: debug : virNetServerClientSendMessageLocked:1122 : msg=0x1769f70 proc=110 len=48 offset=0
>2014-07-07 07:43:34.107+00002887: debug : virNetServerClientSendMessageLocked:1130 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1769db0 len=48 prog=536903814 vers=1 proc=110 type=1 status=0 serial=3
>2014-07-07 07:43:34.107+00002887: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x176cc30 tx=0x1769f70
>2014-07-07 07:43:34.107+00002887: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2014-07-07 07:43:34.107+00002887: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=3
>2014-07-07 07:43:34.107+00002887: debug : virEventPollInterruptLocked:701 : Interrupting
>2014-07-07 07:43:34.107+00002887: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x176f020
>2014-07-07 07:43:34.107+00002887: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1769db0
>2014-07-07 07:43:34.107+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-07 07:43:34.107+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.107+00002885: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-07 07:43:34.107+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-07 07:43:34.107+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.107+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-07 07:43:34.107+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-07 07:43:34.107+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-07 07:43:34.107+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-07 07:43:34.107+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-07 07:43:34.107+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-07-07 07:43:34.107+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-07 07:43:34.107+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=18 e=5 d=0
>2014-07-07 07:43:34.107+00002885: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-07 07:43:34.107+00002885: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1404719019107
>2014-07-07 07:43:34.108+00002885: debug : virEventPollCalculateTimeout:346 : Timeout at 1404719019107 due in 5000 ms
>2014-07-07 07:43:34.108+00002885: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2014-07-07 07:43:34.108+00002885: debug : virEventPollRunOnce:625 : Poll got 2 event(s)
>2014-07-07 07:43:34.108+00002885: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-07 07:43:34.108+00002885: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2014-07-07 07:43:34.108+00002885: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-07 07:43:34.108+00002885: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-07-07 07:43:34.108+00002885: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-07 07:43:34.108+00002885: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-07 07:43:34.108+00002885: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-07 07:43:34.108+00002885: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-07 07:43:34.108+00002885: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-07 07:43:34.108+00002885: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-07 07:43:34.108+00002885: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2014-07-07 07:43:34.108+00002885: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2
>2014-07-07 07:43:34.108+00002885: debug : virNetMessageFree:73 : msg=0x1769f70 nfds=0 cb=(nil)
>2014-07-07 07:43:34.108+00002885: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x176cc30 tx=(nil)
>2014-07-07 07:43:34.108+00002885: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-07 07:43:34.108+00002885: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2014-07-07 07:43:34.108+00002885: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -892856224
>2014-07-07 07:43:34.108+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-07 07:43:34.108+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.108+00002885: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-07 07:43:34.108+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-07 07:43:34.108+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.108+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-07 07:43:34.108+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-07 07:43:34.108+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-07 07:43:34.108+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-07 07:43:34.108+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-07 07:43:34.108+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-07-07 07:43:34.108+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-07 07:43:34.108+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=18 e=1 d=0
>2014-07-07 07:43:34.108+00002885: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-07 07:43:34.108+00002885: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1404719019107
>2014-07-07 07:43:34.108+00002885: debug : virEventPollCalculateTimeout:346 : Timeout at 1404719019107 due in 4999 ms
>2014-07-07 07:43:34.108+00002885: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=4999
>2014-07-07 07:43:34.120+00002885: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2014-07-07 07:43:34.120+00002885: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2014-07-07 07:43:34.120+00002885: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2014-07-07 07:43:34.120+00002885: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2014-07-07 07:43:34.120+00002885: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2014-07-07 07:43:34.120+00002885: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2014-07-07 07:43:34.120+00002885: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2014-07-07 07:43:34.120+00002885: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2014-07-07 07:43:34.120+00002885: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2014-07-07 07:43:34.120+00002885: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2014-07-07 07:43:34.120+00002885: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2014-07-07 07:43:34.120+00002885: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1
>2014-07-07 07:43:34.120+00002885: debug : virNetMessageDecodeLength:152 : Got length, now need 208 total (204 more)
>2014-07-07 07:43:34.120+00002885: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x176cc30 tx=(nil)
>2014-07-07 07:43:34.120+00002885: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-07 07:43:34.120+00002885: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2014-07-07 07:43:34.120+00002885: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -892856224
>2014-07-07 07:43:34.120+00002885: debug : virNetServerClientDispatchRead:878 : RPC_SERVER_CLIENT_MSG_RX: client=0x1769db0 len=208 prog=536903814 vers=1 proc=40 type=0 status=0 serial=4
>2014-07-07 07:43:34.121+00002885: debug : virKeepAliveCheckMessage:384 : ka=0x176a3e0, client=0x1769db0, msg=0x176cc30
>2014-07-07 07:43:34.121+00002885: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=10 frequency=5000
>2014-07-07 07:43:34.121+00002885: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -892856224
>2014-07-07 07:43:34.121+00002885: debug : virObjectRef:168 : OBJECT_REF: obj=0x1769db0
>2014-07-07 07:43:34.121+00002885: debug : virNetServerDispatchNewMessage:218 : server=0x17649c0 client=0x1769db0 message=0x176cc30
>2014-07-07 07:43:34.121+00002885: debug : virObjectRef:168 : OBJECT_REF: obj=0x176f020
>2014-07-07 07:43:34.121+00002885: debug : virNetMessageNew:45 : msg=0x176c1c0 tracked=1
>2014-07-07 07:43:34.121+00002885: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x176c1c0 tx=(nil)
>2014-07-07 07:43:34.121+00002885: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2014-07-07 07:43:34.121+00002888: debug : virNetServerHandleJob:189 : server=0x17649c0 client=0x1769db0 message=0x176cc30 prog=0x176f020
>2014-07-07 07:43:34.121+00002885: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2014-07-07 07:43:34.121+00002888: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=40
>2014-07-07 07:43:34.121+00002885: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -892856224
>2014-07-07 07:43:34.121+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-07 07:43:34.121+00002888: debug : remoteDispatchNetworkCreateXMLHelper:8696 : server=0x17649c0 client=0x1769db0 msg=0x176cc30 rerr=0x7fedc35cbb80 args=0x7fedb4006cb0 ret=0x7fedb4007570
>2014-07-07 07:43:34.121+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.121+00002888: debug : virNetworkCreateXML:10445 : conn=0x7fedb8019a00, xmlDesc=<network>
>  <name>net3</name>
>  <forward mode='nat'/>
>  <bridge name='virbr388' stp='on' delay='0' />
>  <ip address='192.168.13.1' netmask='255.255.255.0'>
>  </ip>
></network>
>
>
>2014-07-07 07:43:34.121+00002885: debug : virEventRunDefaultImpl:244 : running default event implementation
>2014-07-07 07:43:34.121+00002885: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2014-07-07 07:43:34.121+00002885: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2014-07-07 07:43:34.121+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2014-07-07 07:43:34.121+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2014-07-07 07:43:34.121+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2014-07-07 07:43:34.121+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2014-07-07 07:43:34.121+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2014-07-07 07:43:34.121+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2014-07-07 07:43:34.121+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=16 e=1 d=0
>2014-07-07 07:43:34.121+00002885: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=18 e=1 d=0
>2014-07-07 07:43:34.121+00002885: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2014-07-07 07:43:34.121+00002885: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1404719019121
>2014-07-07 07:43:34.121+00002885: debug : virEventPollCalculateTimeout:346 : Timeout at 1404719019121 due in 5000 ms
>2014-07-07 07:43:34.121+00002885: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2014-07-07 07:43:34.122+00002888: debug : virFileClose:72 : Closed fd 21
>2014-07-07 07:43:34.122+00002888: debug : networkStartNetwork:2453 : driver=0x7fedb80008c0, network=0x7fedb4006c60
>2014-07-07 07:43:34.122+00002888: debug : networkStartNetwork:2461 : Beginning network startup process
>2014-07-07 07:43:34.122+00002888: debug : networkStartNetwork:2463 : Setting current network def as transient
>2014-07-07 07:43:34.122+00002888: debug : virFileClose:72 : Closed fd 21
>2014-07-07 07:43:34.122+00002888: debug : networkCheckRouteCollision:2124 : /proc/net/route output:
>Iface	Destination	Gateway 	Flags	RefCnt	Use	Metric	Mask	MTU	Window	IRTT                                                       
>virbr155	009BA8C0	00000000	0001	0	0	0	00FFFFFF	0	0	0                                                                           
>virbr13	000DA8C0	00000000	0001	0	0	0	00FFFFFF0	0	0                                                                            
>virbr1	000CA8C0	00000000	0001	0	0	0	00FFFFFF0	0	0                                                                             
>virbr12	0079A8C0	00000000	0001	0	0	0	00FFFFFF0	0	0                                                                            
>virbr0	007AA8C0	00000000	0001	0	0	0	00FFFFFF0	0	0                                                                             
>eth0	0004420A	00000000	0001	0	0	0	00FCFFFF0	0	0                                                                               
>eth0	0000FEA9	00000000	0001	0	0	1002	0000FFFF0	0	0                                                                            
>eth0	00000000	FE07420A	0003	0	0	0	000000000	0	0                                                                               
>2014-07-07 07:43:34.122+00002888: error : networkCheckRouteCollision:2187 : internal error Network is already in use by interface virbr13
>
>
>     ====== end of log =====
>

Actions: View

Attachments on bug 1116754: 916000