Attachment 580941 Details for Bug 817234 – libvirtd log

libvirtd log

libvirtd.log (text/plain), 64.77 KB, created by zhpeng on 2012-04-28 07:19:29 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: zhpeng

Created: 2012-04-28 07:19:29 UTC

Size: 64.77 KB

patch

obsolete

>2012-04-28 06:40:59.755+0000: 4063: info : libvirt version: 0.9.10, package: 14.el6 (Red Hat, Inc. <http://bugzilla.redhat.com/bugzilla>, 2012-04-24-00:57:34, x86-004.build.bos.redhat.com)
>2012-04-28 06:40:59.755+0000: 4063: error : virCommandWait:2308 : internal error Child process (/sbin/tc qdisc del dev virbr0 root) status unexpected: exit status 2
>2012-04-28 06:40:59.756+0000: 4063: error : virCommandWait:2308 : internal error Child process (/sbin/tc qdisc del dev virbr0 ingress) status unexpected: exit status 2
>2012-04-28 06:41:25.609+0000: 4059: error : virNetDevSetMAC:170 : Cannot set interface MAC on 'virbr0-nic': Cannot assign requested address
>Caught Segmentation violation dumping internal log buffer:
>
>
>    ====== start of log =====
>
>leanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:12.385+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:12.385+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-04-28 06:41:12.385+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-04-28 06:41:12.385+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-04-28 06:41:12.385+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-04-28 06:41:12.385+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-04-28 06:41:12.385+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-04-28 06:41:12.385+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-04-28 06:41:12.385+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=18 e=5 d=0
>2012-04-28 06:41:12.385+00004057: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 4 timers
>2012-04-28 06:41:12.385+00004057: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1335595277385
>2012-04-28 06:41:12.385+00004057: debug : virEventPollCalculateTimeout:351 : Timeout at 1335595277385 due in 5000 ms
>2012-04-28 06:41:12.385+00004057: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 imeout=5000
>2012-04-28 06:41:12.385+00004057: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
>2012-04-28 06:41:12.385+00004057: debug : virEventPollDispatchTimeouts:415 : Dispatch 4
>2012-04-28 06:41:12.385+00004057: debug : virEventPollDispatchHandles:460 : Dispatch 8
>2012-04-28 06:41:12.385+00004057: debug : virEventPollDispatchHandles:474 : i=0 w=1
>2012-04-28 06:41:12.385+00004057: debug : virEventPollDispatchHandles:474 : i=1 w=2
>2012-04-28 06:41:12.385+00004057: debug : virEventPollDispatchHandles:474 : i=2 w=3
>2012-04-28 06:41:12.385+00004057: debug : virEventPollDispatchHandles:474 : i=3 w=4
>2012-04-28 06:41:12.385+00004057: debug : virEventPollDispatchHandles:474 : i=4 w=5
>2012-04-28 06:41:12.385+00004057: debug : virEventPollDispatchHandles:474 : i=5 w=6
>2012-04-28 06:41:12.385+00004057: debug : virEventPollDispatchHandles:474 : i=6 w=7
>2012-04-28 06:41:12.385+00004057: debug : virEventPollDispatchHandles:474 : i=7 w=8
>2012-04-28 06:41:12.385+00004057: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=2
>2012-04-28 06:41:12.385+00004057: debug : virNetMessageFree:75 : msg=0x1240950 nfds=0 cb=(nil)
>2012-04-28 06:41:12.385+00004057: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x12008e0 tx=(nil)
>2012-04-28 06:41:12.385+00004057: debug : virNetServerClientCalculateHandleMode:167 : mode=1
>2012-04-28 06:41:12.385+00004057: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
>2012-04-28 06:41:12.385+00004057: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -471914400
>2012-04-28 06:41:12.385+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:12.385+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:12.385+00004057: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-04-28 06:41:12.385+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:12.385+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:12.385+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-04-28 06:41:12.385+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-04-28 06:41:12.385+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-04-28 06:41:12.385+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-04-28 06:41:12.385+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-04-28 06:41:12.385+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-04-28 06:41:12.385+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-04-28 06:41:12.385+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=18 e=1 d=0
>2012-04-28 06:41:12.385+00004057: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 4 timers
>2012-04-28 06:41:12.385+00004057: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1335595277385
>2012-04-28 06:41:12.385+00004057: debug : virEventPollCalculateTimeout:351 : Timeout at 1335595277385 due in 5000 ms
>2012-04-28 06:41:12.385+00004057: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 imeout=5000
>2012-04-28 06:41:12.385+00004057: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
>2012-04-28 06:41:12.385+00004057: debug : virEventPollDispatchTimeouts:415 : Dispatch 4
>2012-04-28 06:41:12.385+00004057: debug : virEventPollDispatchHandles:460 : Dispatch 8
>2012-04-28 06:41:12.385+00004057: debug : virEventPollDispatchHandles:474 : i=0 w=1
>2012-04-28 06:41:12.385+00004057: debug : virEventPollDispatchHandles:474 : i=1 w=2
>2012-04-28 06:41:12.385+00004057: debug : virEventPollDispatchHandles:474 : i=2 w=3
>2012-04-28 06:41:12.385+00004057: debug : virEventPollDispatchHandles:474 : i=3 w=4
>2012-04-28 06:41:12.385+00004057: debug : virEventPollDispatchHandles:474 : i=4 w=5
>2012-04-28 06:41:12.385+00004057: debug : virEventPollDispatchHandles:474 : i=5 w=6
>2012-04-28 06:41:12.385+00004057: debug : virEventPollDispatchHandles:474 : i=6 w=7
>2012-04-28 06:41:12.385+00004057: debug : virEventPollDispatchHandles:474 : i=7 w=8
>2012-04-28 06:41:12.385+00004057: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=1
>2012-04-28 06:41:12.385+00004057: debug : virNetMessageDecodeLength:149 : Got length, now need 28 total (24 more)
>2012-04-28 06:41:12.385+00004057: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x12008e0 tx=(nil)
>2012-04-28 06:41:12.385+00004057: debug : virNetServerClientCalculateHandleMode:167 : mode=1
>2012-04-28 06:41:12.385+00004057: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
>2012-04-28 06:41:12.385+00004057: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -471914400
>2012-04-28 06:41:12.385+00004057: debug : virNetServerClientDispatchRead:886 : RPC_SERVER_CLIENT_MSG_RX: client=0x12005e0 len=28 prog=1801807216 vers=1 proc=2 type=2 status=0 serial=0
>2012-04-28 06:41:12.385+00004057: debug : virKeepAliveCheckMessage:408 : ka=0x11fb6b0, client=0x12005e0, msg=0x12008e0
>2012-04-28 06:41:12.385+00004057: debug : virKeepAliveCheckMessage:424 : RPC_KEEPALIVE_RECEIVED: ka=0x11fb6b0 client=0x12005e0 prog=1801807216 vers=1 proc=2
>2012-04-28 06:41:12.385+00004057: debug : virKeepAliveCheckMessage:433 : Got keepalive response from client 0x12005e0
>2012-04-28 06:41:12.385+00004057: debug : virEventPollUpdateTimeout:259 : EVENT_POLL_UPDATE_TIMEOUT: timer=14 frequency=5000
>2012-04-28 06:41:12.385+00004057: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -471914400
>2012-04-28 06:41:12.385+00004057: debug : virNetMessageFree:75 : msg=0x12008e0 nfds=0 cb=(nil)
>2012-04-28 06:41:12.385+00004057: debug : virNetMessageNew:48 : msg=0x12008e0 tracked=1
>2012-04-28 06:41:12.385+00004057: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x12008e0 tx=(nil)
>2012-04-28 06:41:12.385+00004057: debug : virNetServerClientCalculateHandleMode:167 : mode=1
>2012-04-28 06:41:12.385+00004057: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
>2012-04-28 06:41:12.385+00004057: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -471914400
>2012-04-28 06:41:12.385+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:12.385+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:12.385+00004057: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-04-28 06:41:12.385+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:12.385+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:12.385+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-04-28 06:41:12.385+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-04-28 06:41:12.385+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-04-28 06:41:12.385+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-04-28 06:41:12.385+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-04-28 06:41:12.385+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-04-28 06:41:12.385+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-04-28 06:41:12.385+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=18 e=1 d=0
>2012-04-28 06:41:12.385+00004057: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 4 timers
>2012-04-28 06:41:12.385+00004057: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1335595277385
>2012-04-28 06:41:12.385+00004057: debug : virEventPollCalculateTimeout:351 : Timeout at 1335595277385 due in 5000 ms
>2012-04-28 06:41:12.385+00004057: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 imeout=5000
>2012-04-28 06:41:17.390+00004057: debug : virEventPollRunOnce:630 : Poll got 0 event(s)
>2012-04-28 06:41:17.390+00004057: debug : virEventPollDispatchTimeouts:415 : Dispatch 4
>2012-04-28 06:41:17.390+00004057: debug : virEventPollDispatchTimeouts:438 : EVENT_POLL_DISPATCH_TIMEOUT: timer=14
>2012-04-28 06:41:17.390+00004057: debug : virKeepAliveTimer:166 : RPC_KEEPALIVE_TIMEOUT: ka=0x11fb6b0 client=0x12005e0 countToDeath=5 idle=5
>2012-04-28 06:41:17.390+00004057: debug : virNetMessageNew:48 : msg=0x1240950 tracked=0
>2012-04-28 06:41:17.390+00004057: debug : virNetMessageEncodePayloadEmpty:441 : Encode length as 28
>2012-04-28 06:41:17.390+00004057: debug : virKeepAliveSend:119 : Sending keepalive request to client 0x12005e0
>2012-04-28 06:41:17.390+00004057: debug : virKeepAliveSend:122 : RPC_KEEPALIVE_SEND: ka=0x11fb6b0 client=0x12005e0 prog=1801807216 vers=1 proc=1
>2012-04-28 06:41:17.390+00004057: debug : virNetServerClientSendMessage:1106 : msg=0x1240950 proc=1 len=28 offset=0
>2012-04-28 06:41:17.390+00004057: debug : virNetServerClientSendMessage:1116 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x12005e0 len=28 prog=1801807216 vers=1 proc=1 type=2 status=0 serial=0
>2012-04-28 06:41:17.390+00004057: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x12008e0 tx=0x1240950
>2012-04-28 06:41:17.390+00004057: debug : virNetServerClientCalculateHandleMode:167 : mode=3
>2012-04-28 06:41:17.390+00004057: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=3
>2012-04-28 06:41:17.390+00004057: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -471914400
>2012-04-28 06:41:17.390+00004057: debug : virEventPollUpdateTimeout:259 : EVENT_POLL_UPDATE_TIMEOUT: timer=14 frequency=5000
>2012-04-28 06:41:17.390+00004057: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -471914400
>2012-04-28 06:41:17.390+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:17.390+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:17.390+00004057: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-04-28 06:41:17.390+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:17.390+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:17.390+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-04-28 06:41:17.390+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-04-28 06:41:17.390+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-04-28 06:41:17.390+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-04-28 06:41:17.390+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-04-28 06:41:17.390+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-04-28 06:41:17.390+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-04-28 06:41:17.390+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=18 e=5 d=0
>2012-04-28 06:41:17.390+00004057: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 4 timers
>2012-04-28 06:41:17.390+00004057: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1335595282390
>2012-04-28 06:41:17.390+00004057: debug : virEventPollCalculateTimeout:351 : Timeout at 1335595282390 due in 5000 ms
>2012-04-28 06:41:17.390+00004057: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 imeout=5000
>2012-04-28 06:41:17.390+00004057: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
>2012-04-28 06:41:17.390+00004057: debug : virEventPollDispatchTimeouts:415 : Dispatch 4
>2012-04-28 06:41:17.390+00004057: debug : virEventPollDispatchHandles:460 : Dispatch 8
>2012-04-28 06:41:17.390+00004057: debug : virEventPollDispatchHandles:474 : i=0 w=1
>2012-04-28 06:41:17.390+00004057: debug : virEventPollDispatchHandles:474 : i=1 w=2
>2012-04-28 06:41:17.390+00004057: debug : virEventPollDispatchHandles:474 : i=2 w=3
>2012-04-28 06:41:17.390+00004057: debug : virEventPollDispatchHandles:474 : i=3 w=4
>2012-04-28 06:41:17.390+00004057: debug : virEventPollDispatchHandles:474 : i=4 w=5
>2012-04-28 06:41:17.390+00004057: debug : virEventPollDispatchHandles:474 : i=5 w=6
>2012-04-28 06:41:17.390+00004057: debug : virEventPollDispatchHandles:474 : i=6 w=7
>2012-04-28 06:41:17.390+00004057: debug : virEventPollDispatchHandles:474 : i=7 w=8
>2012-04-28 06:41:17.390+00004057: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=2
>2012-04-28 06:41:17.390+00004057: debug : virNetMessageFree:75 : msg=0x1240950 nfds=0 cb=(nil)
>2012-04-28 06:41:17.390+00004057: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x12008e0 tx=(nil)
>2012-04-28 06:41:17.390+00004057: debug : virNetServerClientCalculateHandleMode:167 : mode=1
>2012-04-28 06:41:17.390+00004057: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
>2012-04-28 06:41:17.390+00004057: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -471914400
>2012-04-28 06:41:17.390+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:17.390+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:17.390+00004057: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-04-28 06:41:17.390+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:17.390+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:17.390+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-04-28 06:41:17.390+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-04-28 06:41:17.390+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-04-28 06:41:17.390+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-04-28 06:41:17.390+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-04-28 06:41:17.390+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-04-28 06:41:17.390+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-04-28 06:41:17.390+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=18 e=1 d=0
>2012-04-28 06:41:17.390+00004057: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 4 timers
>2012-04-28 06:41:17.390+00004057: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1335595282390
>2012-04-28 06:41:17.390+00004057: debug : virEventPollCalculateTimeout:351 : Timeout at 1335595282390 due in 5000 ms
>2012-04-28 06:41:17.390+00004057: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 imeout=5000
>2012-04-28 06:41:17.391+00004057: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
>2012-04-28 06:41:17.391+00004057: debug : virEventPollDispatchTimeouts:415 : Dispatch 4
>2012-04-28 06:41:17.391+00004057: debug : virEventPollDispatchHandles:460 : Dispatch 8
>2012-04-28 06:41:17.391+00004057: debug : virEventPollDispatchHandles:474 : i=0 w=1
>2012-04-28 06:41:17.391+00004057: debug : virEventPollDispatchHandles:474 : i=1 w=2
>2012-04-28 06:41:17.391+00004057: debug : virEventPollDispatchHandles:474 : i=2 w=3
>2012-04-28 06:41:17.391+00004057: debug : virEventPollDispatchHandles:474 : i=3 w=4
>2012-04-28 06:41:17.391+00004057: debug : virEventPollDispatchHandles:474 : i=4 w=5
>2012-04-28 06:41:17.391+00004057: debug : virEventPollDispatchHandles:474 : i=5 w=6
>2012-04-28 06:41:17.391+00004057: debug : virEventPollDispatchHandles:474 : i=6 w=7
>2012-04-28 06:41:17.391+00004057: debug : virEventPollDispatchHandles:474 : i=7 w=8
>2012-04-28 06:41:17.391+00004057: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=1
>2012-04-28 06:41:17.391+00004057: debug : virNetMessageDecodeLength:149 : Got length, now need 28 total (24 more)
>2012-04-28 06:41:17.391+00004057: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x12008e0 tx=(nil)
>2012-04-28 06:41:17.391+00004057: debug : virNetServerClientCalculateHandleMode:167 : mode=1
>2012-04-28 06:41:17.391+00004057: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
>2012-04-28 06:41:17.391+00004057: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -471914400
>2012-04-28 06:41:17.391+00004057: debug : virNetServerClientDispatchRead:886 : RPC_SERVER_CLIENT_MSG_RX: client=0x12005e0 len=28 prog=1801807216 vers=1 proc=2 type=2 status=0 serial=0
>2012-04-28 06:41:17.391+00004057: debug : virKeepAliveCheckMessage:408 : ka=0x11fb6b0, client=0x12005e0, msg=0x12008e0
>2012-04-28 06:41:17.391+00004057: debug : virKeepAliveCheckMessage:424 : RPC_KEEPALIVE_RECEIVED: ka=0x11fb6b0 client=0x12005e0 prog=1801807216 vers=1 proc=2
>2012-04-28 06:41:17.391+00004057: debug : virKeepAliveCheckMessage:433 : Got keepalive response from client 0x12005e0
>2012-04-28 06:41:17.391+00004057: debug : virEventPollUpdateTimeout:259 : EVENT_POLL_UPDATE_TIMEOUT: timer=14 frequency=5000
>2012-04-28 06:41:17.391+00004057: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -471914400
>2012-04-28 06:41:17.391+00004057: debug : virNetMessageFree:75 : msg=0x12008e0 nfds=0 cb=(nil)
>2012-04-28 06:41:17.391+00004057: debug : virNetMessageNew:48 : msg=0x12008e0 tracked=1
>2012-04-28 06:41:17.391+00004057: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x12008e0 tx=(nil)
>2012-04-28 06:41:17.391+00004057: debug : virNetServerClientCalculateHandleMode:167 : mode=1
>2012-04-28 06:41:17.391+00004057: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
>2012-04-28 06:41:17.391+00004057: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -471914400
>2012-04-28 06:41:17.391+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:17.391+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:17.391+00004057: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-04-28 06:41:17.391+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:17.391+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:17.391+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-04-28 06:41:17.391+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-04-28 06:41:17.391+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-04-28 06:41:17.391+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-04-28 06:41:17.391+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-04-28 06:41:17.391+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-04-28 06:41:17.391+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-04-28 06:41:17.391+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=18 e=1 d=0
>2012-04-28 06:41:17.391+00004057: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 4 timers
>2012-04-28 06:41:17.391+00004057: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1335595282391
>2012-04-28 06:41:17.391+00004057: debug : virEventPollCalculateTimeout:351 : Timeout at 1335595282391 due in 5000 ms
>2012-04-28 06:41:17.391+00004057: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 imeout=5000
>2012-04-28 06:41:21.691+00004057: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchTimeouts:415 : Dispatch 4
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:460 : Dispatch 8
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:474 : i=0 w=1
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:474 : i=1 w=2
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:474 : i=2 w=3
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:474 : i=3 w=4
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:474 : i=4 w=5
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:474 : i=5 w=6
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:474 : i=6 w=7
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:474 : i=7 w=8
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=1
>2012-04-28 06:41:21.691+00004057: debug : virNetMessageDecodeLength:149 : Got length, now need 60 total (56 more)
>2012-04-28 06:41:21.691+00004057: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x12008e0 tx=(nil)
>2012-04-28 06:41:21.691+00004057: debug : virNetServerClientCalculateHandleMode:167 : mode=1
>2012-04-28 06:41:21.691+00004057: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
>2012-04-28 06:41:21.691+00004057: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -471914400
>2012-04-28 06:41:21.691+00004057: debug : virNetServerClientDispatchRead:886 : RPC_SERVER_CLIENT_MSG_RX: client=0x12005e0 len=60 prog=536903814 vers=1 proc=43 type=0 status=0 serial=8
>2012-04-28 06:41:21.691+00004057: debug : virKeepAliveCheckMessage:408 : ka=0x11fb6b0, client=0x12005e0, msg=0x12008e0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollUpdateTimeout:259 : EVENT_POLL_UPDATE_TIMEOUT: timer=14 frequency=5000
>2012-04-28 06:41:21.691+00004057: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -471914400
>2012-04-28 06:41:21.691+00004057: debug : virNetServerDispatchNewMessage:199 : server=0x11efc10 client=0x12005e0 message=0x12008e0
>2012-04-28 06:41:21.691+00004057: debug : virNetServerProgramRef:87 : prog=0x11fadf0 refs=3
>2012-04-28 06:41:21.691+00004060: debug : virNetServerHandleJob:138 : server=0x11efc10 client=0x12005e0 message=0x12008e0 prog=0x11fadf0
>2012-04-28 06:41:21.691+00004060: debug : virNetServerProgramDispatch:269 : prog=536903814 ver=1 type=0 status=0 serial=8 proc=43
>2012-04-28 06:41:21.691+00004057: debug : virNetMessageNew:48 : msg=0x1240950 tracked=1
>2012-04-28 06:41:21.691+00004057: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x1240950 tx=(nil)
>2012-04-28 06:41:21.691+00004057: debug : virNetServerClientCalculateHandleMode:167 : mode=1
>2012-04-28 06:41:21.691+00004057: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
>2012-04-28 06:41:21.691+00004057: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -471914400
>2012-04-28 06:41:21.691+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:21.691+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:21.691+00004057: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-04-28 06:41:21.691+00004060: debug : remoteDispatchNetworkGetXMLDescHelper:8409 : server=0x11efc10 client=0x12005e0 msg=0x12008e0 rerr=0x7f07dcb5dbc0 args=0x7f07b8000970 ret=0x7f07b80008c0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:21.691+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:21.691+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-04-28 06:41:21.691+00004060: debug : virNetworkGetXMLDesc:10235 : network=0x7f07b8000900, flags=0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=18 e=1 d=0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 4 timers
>2012-04-28 06:41:21.691+00004057: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1335595286691
>2012-04-28 06:41:21.691+00004057: debug : virEventPollCalculateTimeout:351 : Timeout at 1335595286691 due in 5000 ms
>2012-04-28 06:41:21.691+00004057: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 imeout=5000
>2012-04-28 06:41:21.691+00004060: debug : virNetworkFree:10069 : network=0x7f07b8000900
>2012-04-28 06:41:21.691+00004060: debug : virUnrefNetwork:407 : unref network 0x7f07b8000900 default 1
>2012-04-28 06:41:21.691+00004060: debug : virReleaseNetwork:369 : release network 0x7f07b8000900 default 0adda191-5e1a-43ef-9039-240076c9a6ca
>2012-04-28 06:41:21.691+00004060: debug : virReleaseNetwork:376 : unref connection 0x7f07cc000bd0 2
>2012-04-28 06:41:21.691+00004060: debug : virNetMessageEncodePayload:351 : Encode length as 384
>2012-04-28 06:41:21.691+00004060: debug : virNetServerClientSendMessage:1106 : msg=0x12008e0 proc=43 len=384 offset=0
>2012-04-28 06:41:21.691+00004060: debug : virNetServerClientSendMessage:1116 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x12005e0 len=384 prog=536903814 vers=1 proc=43 type=1 status=0 serial=8
>2012-04-28 06:41:21.691+00004060: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x1240950 tx=0x12008e0
>2012-04-28 06:41:21.691+00004060: debug : virNetServerClientCalculateHandleMode:167 : mode=3
>2012-04-28 06:41:21.691+00004060: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=3
>2012-04-28 06:41:21.691+00004060: debug : virEventPollInterruptLocked:706 : Interrupting
>2012-04-28 06:41:21.691+00004060: debug : virNetServerProgramFree:527 : prog=0x11fadf0 refs=3
>2012-04-28 06:41:21.691+00004060: debug : virNetServerClientFree:591 : RPC_SERVER_CLIENT_FREE: client=0x12005e0 refs=4
>2012-04-28 06:41:21.691+00004057: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchTimeouts:415 : Dispatch 4
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:460 : Dispatch 8
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:474 : i=0 w=1
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:474 : i=1 w=2
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:474 : i=2 w=3
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:474 : i=3 w=4
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:474 : i=4 w=5
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:474 : i=5 w=6
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:474 : i=6 w=7
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:474 : i=7 w=8
>2012-04-28 06:41:21.691+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:21.691+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:21.691+00004057: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-04-28 06:41:21.691+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:21.691+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:21.691+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=18 e=5 d=0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 4 timers
>2012-04-28 06:41:21.691+00004057: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1335595286691
>2012-04-28 06:41:21.691+00004057: debug : virEventPollCalculateTimeout:351 : Timeout at 1335595286691 due in 5000 ms
>2012-04-28 06:41:21.691+00004057: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 imeout=5000
>2012-04-28 06:41:21.691+00004057: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchTimeouts:415 : Dispatch 4
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:460 : Dispatch 8
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:474 : i=0 w=1
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:474 : i=1 w=2
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:474 : i=2 w=3
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:474 : i=3 w=4
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:474 : i=4 w=5
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:474 : i=5 w=6
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:474 : i=6 w=7
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:474 : i=7 w=8
>2012-04-28 06:41:21.691+00004057: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=2
>2012-04-28 06:41:21.691+00004057: debug : virNetMessageFree:75 : msg=0x12008e0 nfds=0 cb=(nil)
>2012-04-28 06:41:21.691+00004057: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x1240950 tx=(nil)
>2012-04-28 06:41:21.691+00004057: debug : virNetServerClientCalculateHandleMode:167 : mode=1
>2012-04-28 06:41:21.691+00004057: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
>2012-04-28 06:41:21.691+00004057: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -471914400
>2012-04-28 06:41:21.691+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:21.691+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:21.691+00004057: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-04-28 06:41:21.691+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:21.691+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:21.691+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=18 e=1 d=0
>2012-04-28 06:41:21.691+00004057: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 4 timers
>2012-04-28 06:41:21.691+00004057: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1335595286691
>2012-04-28 06:41:21.691+00004057: debug : virEventPollCalculateTimeout:351 : Timeout at 1335595286691 due in 5000 ms
>2012-04-28 06:41:21.691+00004057: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 imeout=5000
>2012-04-28 06:41:21.692+00004057: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
>2012-04-28 06:41:21.692+00004057: debug : virEventPollDispatchTimeouts:415 : Dispatch 4
>2012-04-28 06:41:21.692+00004057: debug : virEventPollDispatchHandles:460 : Dispatch 8
>2012-04-28 06:41:21.692+00004057: debug : virEventPollDispatchHandles:474 : i=0 w=1
>2012-04-28 06:41:21.692+00004057: debug : virEventPollDispatchHandles:474 : i=1 w=2
>2012-04-28 06:41:21.692+00004057: debug : virEventPollDispatchHandles:474 : i=2 w=3
>2012-04-28 06:41:21.692+00004057: debug : virEventPollDispatchHandles:474 : i=3 w=4
>2012-04-28 06:41:21.692+00004057: debug : virEventPollDispatchHandles:474 : i=4 w=5
>2012-04-28 06:41:21.692+00004057: debug : virEventPollDispatchHandles:474 : i=5 w=6
>2012-04-28 06:41:21.692+00004057: debug : virEventPollDispatchHandles:474 : i=6 w=7
>2012-04-28 06:41:21.692+00004057: debug : virEventPollDispatchHandles:474 : i=7 w=8
>2012-04-28 06:41:21.692+00004057: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=1
>2012-04-28 06:41:21.692+00004057: debug : virNetMessageDecodeLength:149 : Got length, now need 384 total (380 more)
>2012-04-28 06:41:21.692+00004057: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x1240950 tx=(nil)
>2012-04-28 06:41:21.692+00004057: debug : virNetServerClientCalculateHandleMode:167 : mode=1
>2012-04-28 06:41:21.692+00004057: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
>2012-04-28 06:41:21.692+00004057: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -471914400
>2012-04-28 06:41:21.692+00004057: debug : virNetServerClientDispatchRead:886 : RPC_SERVER_CLIENT_MSG_RX: client=0x12005e0 len=384 prog=536903814 vers=1 proc=41 type=0 status=0 serial=9
>2012-04-28 06:41:21.692+00004057: debug : virKeepAliveCheckMessage:408 : ka=0x11fb6b0, client=0x12005e0, msg=0x1240950
>2012-04-28 06:41:21.692+00004057: debug : virEventPollUpdateTimeout:259 : EVENT_POLL_UPDATE_TIMEOUT: timer=14 frequency=5000
>2012-04-28 06:41:21.692+00004057: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -471914400
>2012-04-28 06:41:21.692+00004057: debug : virNetServerDispatchNewMessage:199 : server=0x11efc10 client=0x12005e0 message=0x1240950
>2012-04-28 06:41:21.692+00004057: debug : virNetServerProgramRef:87 : prog=0x11fadf0 refs=3
>2012-04-28 06:41:21.692+00004065: debug : virNetServerHandleJob:138 : server=0x11efc10 client=0x12005e0 message=0x1240950 prog=0x11fadf0
>2012-04-28 06:41:21.692+00004057: debug : virNetMessageNew:48 : msg=0x12008e0 tracked=1
>2012-04-28 06:41:21.692+00004057: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x12008e0 tx=(nil)
>2012-04-28 06:41:21.692+00004057: debug : virNetServerClientCalculateHandleMode:167 : mode=1
>2012-04-28 06:41:21.692+00004057: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
>2012-04-28 06:41:21.692+00004057: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -471914400
>2012-04-28 06:41:21.692+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:21.692+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:21.692+00004057: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-04-28 06:41:21.692+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:21.692+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:21.692+00004065: debug : virNetServerProgramDispatch:269 : prog=536903814 ver=1 type=0 status=0 serial=9 proc=41
>2012-04-28 06:41:21.692+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-04-28 06:41:21.692+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-04-28 06:41:21.692+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-04-28 06:41:21.692+00004065: debug : remoteDispatchNetworkDefineXMLHelper:8193 : server=0x11efc10 client=0x12005e0 msg=0x1240950 rerr=0x7f07d9958bc0 args=0x7f07c4000900 ret=0x7f07c40009a0
>2012-04-28 06:41:21.692+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-04-28 06:41:21.692+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-04-28 06:41:21.692+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-04-28 06:41:21.692+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-04-28 06:41:21.692+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=18 e=1 d=0
>2012-04-28 06:41:21.692+00004065: debug : virNetworkDefineXML:9894 : conn=0x7f07cc000bd0, xml=<network>
>  <name>default</name>
>  <uuid>0adda191-5e1a-43ef-9039-240076c9a6ca</uuid>
>  <forward mode='nat'/>
>  <bridge name='virbr0' stp='on' delay='0' />
>  <mac address='FF:FF:DD:DD:EE:EE'/>
>  <ip address='192.168.122.1' netmask='255.255.255.0'>
>    <dhcp>
>      <range start='192.168.122.2' end='192.168.122.254' />
>    </dhcp>
>  </ip>
></network>
>
>2012-04-28 06:41:21.692+00004057: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 4 timers
>2012-04-28 06:41:21.692+00004057: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1335595286692
>2012-04-28 06:41:21.692+00004057: debug : virEventPollCalculateTimeout:351 : Timeout at 1335595286692 due in 5000 ms
>2012-04-28 06:41:21.692+00004057: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 imeout=5000
>2012-04-28 06:41:21.724+00004065: info : networkDefine:2369 : Defining network 'default'
>2012-04-28 06:41:21.724+00004065: debug : virNetworkFree:10069 : network=0x7f07c40009c0
>2012-04-28 06:41:21.724+00004065: debug : virUnrefNetwork:407 : unref network 0x7f07c40009c0 default 1
>2012-04-28 06:41:21.724+00004065: debug : virReleaseNetwork:369 : release network 0x7f07c40009c0 default 0adda191-5e1a-43ef-9039-240076c9a6ca
>2012-04-28 06:41:21.724+00004065: debug : virReleaseNetwork:376 : unref connection 0x7f07cc000bd0 2
>2012-04-28 06:41:21.724+00004065: debug : virNetMessageEncodePayload:351 : Encode length as 56
>2012-04-28 06:41:21.724+00004065: debug : virNetServerClientSendMessage:1106 : msg=0x1240950 proc=41 len=56 offset=0
>2012-04-28 06:41:21.724+00004065: debug : virNetServerClientSendMessage:1116 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x12005e0 len=56 prog=536903814 vers=1 proc=41 type=1 status=0 serial=9
>2012-04-28 06:41:21.724+00004065: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x12008e0 tx=0x1240950
>2012-04-28 06:41:21.724+00004065: debug : virNetServerClientCalculateHandleMode:167 : mode=3
>2012-04-28 06:41:21.724+00004065: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=3
>2012-04-28 06:41:21.724+00004065: debug : virEventPollInterruptLocked:706 : Interrupting
>2012-04-28 06:41:21.724+00004065: debug : virNetServerProgramFree:527 : prog=0x11fadf0 refs=3
>2012-04-28 06:41:21.724+00004065: debug : virNetServerClientFree:591 : RPC_SERVER_CLIENT_FREE: client=0x12005e0 refs=4
>2012-04-28 06:41:21.724+00004057: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
>2012-04-28 06:41:21.724+00004057: debug : virEventPollDispatchTimeouts:415 : Dispatch 4
>2012-04-28 06:41:21.724+00004057: debug : virEventPollDispatchHandles:460 : Dispatch 8
>2012-04-28 06:41:21.724+00004057: debug : virEventPollDispatchHandles:474 : i=0 w=1
>2012-04-28 06:41:21.724+00004057: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-04-28 06:41:21.724+00004057: debug : virEventPollDispatchHandles:474 : i=1 w=2
>2012-04-28 06:41:21.724+00004057: debug : virEventPollDispatchHandles:474 : i=2 w=3
>2012-04-28 06:41:21.724+00004057: debug : virEventPollDispatchHandles:474 : i=3 w=4
>2012-04-28 06:41:21.724+00004057: debug : virEventPollDispatchHandles:474 : i=4 w=5
>2012-04-28 06:41:21.724+00004057: debug : virEventPollDispatchHandles:474 : i=5 w=6
>2012-04-28 06:41:21.724+00004057: debug : virEventPollDispatchHandles:474 : i=6 w=7
>2012-04-28 06:41:21.724+00004057: debug : virEventPollDispatchHandles:474 : i=7 w=8
>2012-04-28 06:41:21.724+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:21.724+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:21.724+00004057: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-04-28 06:41:21.724+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:21.724+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:21.724+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-04-28 06:41:21.724+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-04-28 06:41:21.724+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-04-28 06:41:21.724+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-04-28 06:41:21.724+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-04-28 06:41:21.724+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-04-28 06:41:21.724+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-04-28 06:41:21.724+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=18 e=5 d=0
>2012-04-28 06:41:21.724+00004057: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 4 timers
>2012-04-28 06:41:21.724+00004057: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1335595286692
>2012-04-28 06:41:21.724+00004057: debug : virEventPollCalculateTimeout:351 : Timeout at 1335595286692 due in 4968 ms
>2012-04-28 06:41:21.724+00004057: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 imeout=4968
>2012-04-28 06:41:21.724+00004057: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
>2012-04-28 06:41:21.724+00004057: debug : virEventPollDispatchTimeouts:415 : Dispatch 4
>2012-04-28 06:41:21.724+00004057: debug : virEventPollDispatchHandles:460 : Dispatch 8
>2012-04-28 06:41:21.724+00004057: debug : virEventPollDispatchHandles:474 : i=0 w=1
>2012-04-28 06:41:21.724+00004057: debug : virEventPollDispatchHandles:474 : i=1 w=2
>2012-04-28 06:41:21.724+00004057: debug : virEventPollDispatchHandles:474 : i=2 w=3
>2012-04-28 06:41:21.724+00004057: debug : virEventPollDispatchHandles:474 : i=3 w=4
>2012-04-28 06:41:21.724+00004057: debug : virEventPollDispatchHandles:474 : i=4 w=5
>2012-04-28 06:41:21.724+00004057: debug : virEventPollDispatchHandles:474 : i=5 w=6
>2012-04-28 06:41:21.724+00004057: debug : virEventPollDispatchHandles:474 : i=6 w=7
>2012-04-28 06:41:21.724+00004057: debug : virEventPollDispatchHandles:474 : i=7 w=8
>2012-04-28 06:41:21.724+00004057: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=2
>2012-04-28 06:41:21.724+00004057: debug : virNetMessageFree:75 : msg=0x1240950 nfds=0 cb=(nil)
>2012-04-28 06:41:21.724+00004057: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x12008e0 tx=(nil)
>2012-04-28 06:41:21.724+00004057: debug : virNetServerClientCalculateHandleMode:167 : mode=1
>2012-04-28 06:41:21.724+00004057: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
>2012-04-28 06:41:21.724+00004057: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -471914400
>2012-04-28 06:41:21.724+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:21.724+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:21.724+00004057: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-04-28 06:41:21.724+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:21.724+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:21.724+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-04-28 06:41:21.724+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-04-28 06:41:21.724+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-04-28 06:41:21.724+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-04-28 06:41:21.724+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-04-28 06:41:21.724+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-04-28 06:41:21.724+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-04-28 06:41:21.724+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=18 e=1 d=0
>2012-04-28 06:41:21.724+00004057: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 4 timers
>2012-04-28 06:41:21.724+00004057: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1335595286692
>2012-04-28 06:41:21.724+00004057: debug : virEventPollCalculateTimeout:351 : Timeout at 1335595286692 due in 4968 ms
>2012-04-28 06:41:21.724+00004057: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 imeout=4968
>2012-04-28 06:41:25.604+00004057: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchTimeouts:415 : Dispatch 4
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:460 : Dispatch 8
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=0 w=1
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=1 w=2
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=2 w=3
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=3 w=4
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=4 w=5
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=5 w=6
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=6 w=7
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=7 w=8
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=1
>2012-04-28 06:41:25.604+00004057: debug : virNetMessageDecodeLength:149 : Got length, now need 40 total (36 more)
>2012-04-28 06:41:25.604+00004057: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x12008e0 tx=(nil)
>2012-04-28 06:41:25.604+00004057: debug : virNetServerClientCalculateHandleMode:167 : mode=1
>2012-04-28 06:41:25.604+00004057: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
>2012-04-28 06:41:25.604+00004057: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -471914400
>2012-04-28 06:41:25.604+00004057: debug : virNetServerClientDispatchRead:886 : RPC_SERVER_CLIENT_MSG_RX: client=0x12005e0 len=40 prog=536903814 vers=1 proc=46 type=0 status=0 serial=10
>2012-04-28 06:41:25.604+00004057: debug : virKeepAliveCheckMessage:408 : ka=0x11fb6b0, client=0x12005e0, msg=0x12008e0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollUpdateTimeout:259 : EVENT_POLL_UPDATE_TIMEOUT: timer=14 frequency=5000
>2012-04-28 06:41:25.604+00004057: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -471914400
>2012-04-28 06:41:25.604+00004057: debug : virNetServerDispatchNewMessage:199 : server=0x11efc10 client=0x12005e0 message=0x12008e0
>2012-04-28 06:41:25.604+00004057: debug : virNetServerProgramRef:87 : prog=0x11fadf0 refs=3
>2012-04-28 06:41:25.604+00004063: debug : virNetServerHandleJob:138 : server=0x11efc10 client=0x12005e0 message=0x12008e0 prog=0x11fadf0
>2012-04-28 06:41:25.604+00004063: debug : virNetServerProgramDispatch:269 : prog=536903814 ver=1 type=0 status=0 serial=10 proc=46
>2012-04-28 06:41:25.604+00004057: debug : virNetMessageNew:48 : msg=0x1240950 tracked=1
>2012-04-28 06:41:25.604+00004057: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x1240950 tx=(nil)
>2012-04-28 06:41:25.604+00004057: debug : virNetServerClientCalculateHandleMode:167 : mode=1
>2012-04-28 06:41:25.604+00004057: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
>2012-04-28 06:41:25.604+00004057: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -471914400
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:25.604+00004057: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=18 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 4 timers
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1335595290604
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCalculateTimeout:351 : Timeout at 1335595290604 due in 5000 ms
>2012-04-28 06:41:25.604+00004057: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 imeout=5000
>2012-04-28 06:41:25.604+00004063: debug : remoteDispatchNetworkLookupByNameHelper:8577 : server=0x11efc10 client=0x12005e0 msg=0x12008e0 rerr=0x7f07dad5abc0 args=0x7f07d00cd9d0 ret=0x7f07d0013980
>2012-04-28 06:41:25.604+00004063: debug : virNetworkLookupByName:9725 : conn=0x7f07cc000bd0, name=default
>2012-04-28 06:41:25.604+00004063: debug : virNetworkFree:10069 : network=0x7f07d0173500
>2012-04-28 06:41:25.604+00004063: debug : virUnrefNetwork:407 : unref network 0x7f07d0173500 default 1
>2012-04-28 06:41:25.604+00004063: debug : virReleaseNetwork:369 : release network 0x7f07d0173500 default 0adda191-5e1a-43ef-9039-240076c9a6ca
>2012-04-28 06:41:25.604+00004063: debug : virReleaseNetwork:376 : unref connection 0x7f07cc000bd0 2
>2012-04-28 06:41:25.604+00004063: debug : virNetMessageEncodePayload:351 : Encode length as 56
>2012-04-28 06:41:25.604+00004063: debug : virNetServerClientSendMessage:1106 : msg=0x12008e0 proc=46 len=56 offset=0
>2012-04-28 06:41:25.604+00004063: debug : virNetServerClientSendMessage:1116 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x12005e0 len=56 prog=536903814 vers=1 proc=46 type=1 status=0 serial=10
>2012-04-28 06:41:25.604+00004063: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x1240950 tx=0x12008e0
>2012-04-28 06:41:25.604+00004063: debug : virNetServerClientCalculateHandleMode:167 : mode=3
>2012-04-28 06:41:25.604+00004063: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=3
>2012-04-28 06:41:25.604+00004063: debug : virEventPollInterruptLocked:706 : Interrupting
>2012-04-28 06:41:25.604+00004063: debug : virNetServerProgramFree:527 : prog=0x11fadf0 refs=3
>2012-04-28 06:41:25.604+00004057: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
>2012-04-28 06:41:25.604+00004063: debug : virNetServerClientFree:591 : RPC_SERVER_CLIENT_FREE: client=0x12005e0 refs=4
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchTimeouts:415 : Dispatch 4
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:460 : Dispatch 8
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=0 w=1
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=1 w=2
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=2 w=3
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=3 w=4
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=4 w=5
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=5 w=6
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=6 w=7
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=7 w=8
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:25.604+00004057: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=18 e=5 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 4 timers
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1335595290604
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCalculateTimeout:351 : Timeout at 1335595290604 due in 5000 ms
>2012-04-28 06:41:25.604+00004057: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 imeout=5000
>2012-04-28 06:41:25.604+00004057: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchTimeouts:415 : Dispatch 4
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:460 : Dispatch 8
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=0 w=1
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=1 w=2
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=2 w=3
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=3 w=4
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=4 w=5
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=5 w=6
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=6 w=7
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=7 w=8
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=2
>2012-04-28 06:41:25.604+00004057: debug : virNetMessageFree:75 : msg=0x12008e0 nfds=0 cb=(nil)
>2012-04-28 06:41:25.604+00004057: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x1240950 tx=(nil)
>2012-04-28 06:41:25.604+00004057: debug : virNetServerClientCalculateHandleMode:167 : mode=1
>2012-04-28 06:41:25.604+00004057: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
>2012-04-28 06:41:25.604+00004057: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -471914400
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:25.604+00004057: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=18 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 4 timers
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1335595290604
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCalculateTimeout:351 : Timeout at 1335595290604 due in 5000 ms
>2012-04-28 06:41:25.604+00004057: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 imeout=5000
>2012-04-28 06:41:25.604+00004057: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchTimeouts:415 : Dispatch 4
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:460 : Dispatch 8
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=0 w=1
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=1 w=2
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=2 w=3
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=3 w=4
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=4 w=5
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=5 w=6
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=6 w=7
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:474 : i=7 w=8
>2012-04-28 06:41:25.604+00004057: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=8 events=1
>2012-04-28 06:41:25.604+00004057: debug : virNetMessageDecodeLength:149 : Got length, now need 56 total (52 more)
>2012-04-28 06:41:25.604+00004057: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x1240950 tx=(nil)
>2012-04-28 06:41:25.604+00004057: debug : virNetServerClientCalculateHandleMode:167 : mode=1
>2012-04-28 06:41:25.604+00004057: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
>2012-04-28 06:41:25.604+00004057: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -471914400
>2012-04-28 06:41:25.604+00004057: debug : virNetServerClientDispatchRead:886 : RPC_SERVER_CLIENT_MSG_RX: client=0x12005e0 len=56 prog=536903814 vers=1 proc=39 type=0 status=0 serial=11
>2012-04-28 06:41:25.604+00004057: debug : virKeepAliveCheckMessage:408 : ka=0x11fb6b0, client=0x12005e0, msg=0x1240950
>2012-04-28 06:41:25.604+00004057: debug : virEventPollUpdateTimeout:259 : EVENT_POLL_UPDATE_TIMEOUT: timer=14 frequency=5000
>2012-04-28 06:41:25.604+00004057: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -471914400
>2012-04-28 06:41:25.604+00004057: debug : virNetServerDispatchNewMessage:199 : server=0x11efc10 client=0x12005e0 message=0x1240950
>2012-04-28 06:41:25.604+00004057: debug : virNetServerProgramRef:87 : prog=0x11fadf0 refs=3
>2012-04-28 06:41:25.604+00004059: debug : virNetServerHandleJob:138 : server=0x11efc10 client=0x12005e0 message=0x1240950 prog=0x11fadf0
>2012-04-28 06:41:25.604+00004059: debug : virNetServerProgramDispatch:269 : prog=536903814 ver=1 type=0 status=0 serial=11 proc=39
>2012-04-28 06:41:25.604+00004057: debug : virNetMessageNew:48 : msg=0x12008e0 tracked=1
>2012-04-28 06:41:25.604+00004057: debug : virNetServerClientCalculateHandleMode:137 : tls=(nil) hs=-1, rx=0x12008e0 tx=(nil)
>2012-04-28 06:41:25.604+00004057: debug : virNetServerClientCalculateHandleMode:167 : mode=1
>2012-04-28 06:41:25.604+00004057: debug : virEventPollUpdateHandle:151 : EVENT_POLL_UPDATE_HANDLE: watch=8 events=1
>2012-04-28 06:41:25.604+00004057: debug : virEventPollInterruptLocked:702 : Skip interrupt, 1 -471914400
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:25.604+00004057: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-04-28 06:41:25.604+00004059: debug : remoteDispatchNetworkCreateHelper:8088 : server=0x11efc10 client=0x12005e0 msg=0x1240950 rerr=0x7f07dd55ebc0 args=0x7f07c8000ba0 ret=0x7f07c80008c0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCleanupTimeouts:506 : Cleanup 4
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCleanupHandles:554 : Cleanup 8
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-04-28 06:41:25.604+00004059: debug : virNetworkCreate:9981 : network=0x7f07c8000ac0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=5 w=6, f=16 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollMakePollFDs:383 : Prepare n=7 w=8, f=18 e=1 d=0
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 4 timers
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCalculateTimeout:331 : Got a timeout scheduled for 1335595290604
>2012-04-28 06:41:25.604+00004057: debug : virEventPollCalculateTimeout:351 : Timeout at 1335595290604 due in 5000 ms
>2012-04-28 06:41:25.604+00004057: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=8 imeout=5000
>2012-04-28 06:41:25.608+00004059: debug : networkCheckRouteCollision:1642 : /proc/net/route output:
>Iface	Destination	Gateway 	Flags	RefCnt	Use	Metric	Mask		MTU	Window	IRTT                                                       
>em1	0004420A	00000000	0001	0	0	0	00FCFFFF	0	0	0                                                                                
>em1	0000FEA9	00000000	0001	0	0	1002	0000FFFF	0	0	0                                                                             
>em1	00000000	FE07420A	0003	0	0	0	00000000	0	0	0                                                                                
>2012-04-28 06:41:25.609+00004059: error : virNetDevSetMAC:170 : Cannot set interface MAC on 'virbr0-nic': Cannot assign requested address
>
>
>     ====== end of log =====
>

Actions: View

Attachments on bug 817234: 580941