Attachment 650212 Details for Bug 879473 – libvirtd crash log

libvirtd crash log

libvirtd.log-crash (text/plain), 64.12 KB, created by weizhang on 2012-11-23 05:54:02 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: weizhang

Created: 2012-11-23 05:54:02 UTC

Size: 64.12 KB

patch

obsolete

>
>Caught Segmentation violation dumping internal log buffer:
>
>
>    ====== start of log =====
>
>ventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-23 05:46:14.232+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-23 05:46:14.232+000013746: debug : virKeepAliveStart:289 : RPC_KEEPALIVE_START: ka=0x10e8b60 client=0x10e8670 interval=0 count=0
>2012-11-23 05:46:14.232+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=21 e=25 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=22 e=25 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=24 e=25 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=23 e=25 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=37, f=19 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2012-11-23 05:46:14.233+000013743: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2012-11-23 05:46:14.233+000013743: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=-1
>2012-11-23 05:46:14.233+000013746: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-11-23 05:46:14.233+000013746: debug : virEventPollAddTimeout:243 : EVENT_POLL_ADD_TIMEOUT: timer=52 frequency=5000 cb=0x7fc70e92bcd0 opaque=0x10e8b60 ff=0x41f328
>2012-11-23 05:46:14.233+000013746: debug : virObjectRef:168 : OBJECT_REF: obj=0x10e8b60
>2012-11-23 05:46:14.233+000013746: debug : virNetMessageEncodePayload:358 : Encode length as 32
>2012-11-23 05:46:14.233+000013743: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-23 05:46:14.233+000013746: debug : virNetServerClientSendMessageLocked:1116 : msg=0x10e51c0 proc=60 len=32 offset=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-23 05:46:14.233+000013746: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x10e8670 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=1
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-11-23 05:46:14.233+000013746: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x10e63f0 tx=0x10e51c0
>2012-11-23 05:46:14.233+000013746: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-23 05:46:14.233+000013746: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=37 events=3
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=11 w=37
>2012-11-23 05:46:14.233+000013743: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-23 05:46:14.233+000013743: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-23 05:46:14.233+000013746: debug : virEventPollInterruptLocked:697 : Skip interrupt, 0 243017824
>2012-11-23 05:46:14.233+000013746: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x10e6040
>2012-11-23 05:46:14.233+000013746: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x10e8670
>2012-11-23 05:46:14.233+000013743: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-23 05:46:14.233+000013743: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-23 05:46:14.233+000013743: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=21 e=25 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=22 e=25 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=24 e=25 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=23 e=25 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=37, f=19 e=5 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-23 05:46:14.233+000013743: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1353649579232
>2012-11-23 05:46:14.233+000013743: debug : virEventPollCalculateTimeout:346 : Timeout at 1353649579232 due in 4999 ms
>2012-11-23 05:46:14.233+000013743: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=4999
>2012-11-23 05:46:14.233+000013743: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=11 w=37
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=37 events=2
>2012-11-23 05:46:14.233+000013743: debug : virNetMessageFree:73 : msg=0x10e51c0 nfds=0 cb=(nil)
>2012-11-23 05:46:14.233+000013743: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x10e63f0 tx=(nil)
>2012-11-23 05:46:14.233+000013743: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-23 05:46:14.233+000013743: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=37 events=1
>2012-11-23 05:46:14.233+000013743: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 243017824
>2012-11-23 05:46:14.233+000013743: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-23 05:46:14.233+000013743: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-23 05:46:14.233+000013743: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-23 05:46:14.233+000013743: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-23 05:46:14.233+000013743: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=21 e=25 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=22 e=25 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=24 e=25 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=23 e=25 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=37, f=19 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-23 05:46:14.233+000013743: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1353649579232
>2012-11-23 05:46:14.233+000013743: debug : virEventPollCalculateTimeout:346 : Timeout at 1353649579232 due in 4999 ms
>2012-11-23 05:46:14.233+000013743: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=4999
>2012-11-23 05:46:14.233+000013743: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:469 : i=11 w=37
>2012-11-23 05:46:14.233+000013743: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=37 events=1
>2012-11-23 05:46:14.233+000013743: debug : virNetMessageDecodeLength:152 : Got length, now need 40 total (36 more)
>2012-11-23 05:46:14.233+000013743: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x10e63f0 tx=(nil)
>2012-11-23 05:46:14.233+000013743: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-23 05:46:14.233+000013743: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=37 events=1
>2012-11-23 05:46:14.233+000013743: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 243017824
>2012-11-23 05:46:14.233+000013743: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x10e8670 len=40 prog=536903814 vers=1 proc=1 type=0 status=0 serial=2
>2012-11-23 05:46:14.233+000013743: debug : virKeepAliveCheckMessage:384 : ka=0x10e8b60, client=0x10e8670, msg=0x10e63f0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=52 frequency=5000
>2012-11-23 05:46:14.233+000013743: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 243017824
>2012-11-23 05:46:14.233+000013743: debug : virObjectRef:168 : OBJECT_REF: obj=0x10e8670
>2012-11-23 05:46:14.233+000013743: debug : virNetServerDispatchNewMessage:218 : server=0x10dacb0 client=0x10e8670 message=0x10e63f0
>2012-11-23 05:46:14.233+000013743: debug : virObjectRef:168 : OBJECT_REF: obj=0x10e6040
>2012-11-23 05:46:14.233+000013743: debug : virNetMessageNew:45 : msg=0x10e3810 tracked=1
>2012-11-23 05:46:14.233+000013743: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x10e3810 tx=(nil)
>2012-11-23 05:46:14.233+000013743: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-23 05:46:14.233+000013743: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=37 events=1
>2012-11-23 05:46:14.233+000013747: debug : virNetServerHandleJob:189 : server=0x10dacb0 client=0x10e8670 message=0x10e63f0 prog=0x10e6040
>2012-11-23 05:46:14.233+000013743: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 243017824
>2012-11-23 05:46:14.233+000013743: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-23 05:46:14.233+000013747: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=2 proc=1
>2012-11-23 05:46:14.233+000013743: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-23 05:46:14.233+000013743: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-23 05:46:14.233+000013747: debug : remoteDispatchOpenHelper:11127 : server=0x10dacb0 client=0x10e8670 msg=0x10e63f0 rerr=0x7fc706b2cb80 args=0x7fc6fc12ffc0 ret=0x7fc6fc07daa0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-23 05:46:14.233+000013743: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-23 05:46:14.233+000013747: debug : remoteDispatchOpen:731 : priv=0x10e8910 conn=(nil)
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-23 05:46:14.233+000013747: debug : virConnectOpen:1331 : name=
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-23 05:46:14.233+000013747: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7fc6fc1826b0 classname=virConnect
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=21 e=25 d=0
>2012-11-23 05:46:14.233+000013747: debug : virConnectGetConfigFile:953 : Loading config file '/etc/libvirt/libvirt.conf'
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=22 e=25 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=24 e=25 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=23 e=25 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=37, f=19 e=1 d=0
>2012-11-23 05:46:14.233+000013743: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-23 05:46:14.233+000013743: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1353649579233
>2012-11-23 05:46:14.233+000013743: debug : virEventPollCalculateTimeout:346 : Timeout at 1353649579233 due in 5000 ms
>2012-11-23 05:46:14.233+000013743: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=5000
>2012-11-23 05:46:14.233+000013747: debug : virFileClose:72 : Closed fd 20
>2012-11-23 05:46:14.233+000013747: debug : do_open:1131 : no name, allowing driver auto-select
>2012-11-23 05:46:14.233+000013747: debug : do_open:1174 : trying driver 0 (Test) ...
>2012-11-23 05:46:14.233+000013747: debug : do_open:1180 : driver 0 Test returned DECLINED
>2012-11-23 05:46:14.233+000013747: debug : do_open:1174 : trying driver 1 (ESX) ...
>2012-11-23 05:46:14.233+000013747: debug : do_open:1180 : driver 1 ESX returned DECLINED
>2012-11-23 05:46:14.233+000013747: debug : do_open:1174 : trying driver 2 (remote) ...
>2012-11-23 05:46:14.233+000013747: debug : do_open:1180 : driver 2 remote returned DECLINED
>2012-11-23 05:46:14.233+000013747: debug : do_open:1174 : trying driver 3 (QEMU) ...
>2012-11-23 05:46:14.233+000013747: debug : do_open:1180 : driver 3 QEMU returned SUCCESS
>2012-11-23 05:46:14.233+000013747: debug : do_open:1202 : network driver 0 Test returned DECLINED
>2012-11-23 05:46:14.233+000013747: debug : do_open:1202 : network driver 1 ESX returned DECLINED
>2012-11-23 05:46:14.233+000013747: debug : do_open:1202 : network driver 2 remote returned DECLINED
>2012-11-23 05:46:14.233+000013747: debug : do_open:1202 : network driver 3 Network returned SUCCESS
>2012-11-23 05:46:14.233+000013747: debug : do_open:1217 : interface driver 0 Test returned DECLINED
>2012-11-23 05:46:14.233+000013747: debug : do_open:1217 : interface driver 1 ESX returned DECLINED
>2012-11-23 05:46:14.233+000013747: debug : do_open:1217 : interface driver 2 remote returned DECLINED
>2012-11-23 05:46:14.238+000013747: debug : do_open:1217 : interface driver 3 Interface returned SUCCESS
>2012-11-23 05:46:14.238+000013747: debug : do_open:1233 : storage driver 0 Test returned DECLINED
>2012-11-23 05:46:14.238+000013747: debug : do_open:1233 : storage driver 1 ESX returned DECLINED
>2012-11-23 05:46:14.238+000013747: debug : do_open:1233 : storage driver 2 remote returned DECLINED
>2012-11-23 05:46:14.238+000013747: debug : do_open:1233 : storage driver 3 storage returned SUCCESS
>2012-11-23 05:46:14.238+000013747: debug : do_open:1249 : node driver 0 Test returned DECLINED
>2012-11-23 05:46:14.238+000013747: debug : do_open:1249 : node driver 1 ESX returned DECLINED
>2012-11-23 05:46:14.238+000013747: debug : do_open:1249 : node driver 2 remote returned DECLINED
>2012-11-23 05:46:14.238+000013747: debug : do_open:1249 : node driver 3 udevDeviceMonitor returned SUCCESS
>2012-11-23 05:46:14.238+000013747: debug : do_open:1265 : secret driver 0 Test returned DECLINED
>2012-11-23 05:46:14.238+000013747: debug : do_open:1265 : secret driver 1 ESX returned DECLINED
>2012-11-23 05:46:14.238+000013747: debug : do_open:1265 : secret driver 2 remote returned DECLINED
>2012-11-23 05:46:14.238+000013747: debug : do_open:1265 : secret driver 3 secret returned SUCCESS
>2012-11-23 05:46:14.238+000013747: debug : do_open:1281 : nwfilter driver 0 Test returned DECLINED
>2012-11-23 05:46:14.238+000013747: debug : do_open:1281 : nwfilter driver 1 ESX returned DECLINED
>2012-11-23 05:46:14.238+000013747: debug : do_open:1281 : nwfilter driver 2 remote returned DECLINED
>2012-11-23 05:46:14.238+000013747: debug : do_open:1281 : nwfilter driver 3 nwfilter returned SUCCESS
>2012-11-23 05:46:14.238+000013747: debug : virNetMessageEncodePayload:358 : Encode length as 28
>2012-11-23 05:46:14.238+000013747: debug : virNetServerClientSendMessageLocked:1116 : msg=0x10e63f0 proc=1 len=28 offset=0
>2012-11-23 05:46:14.238+000013747: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x10e8670 len=28 prog=536903814 vers=1 proc=1 type=1 status=0 serial=2
>2012-11-23 05:46:14.238+000013747: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x10e3810 tx=0x10e63f0
>2012-11-23 05:46:14.238+000013747: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2012-11-23 05:46:14.238+000013747: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=37 events=3
>2012-11-23 05:46:14.238+000013747: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-11-23 05:46:14.238+000013747: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x10e6040
>2012-11-23 05:46:14.238+000013747: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x10e8670
>2012-11-23 05:46:14.238+000013743: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=11 w=37
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-23 05:46:14.238+000013743: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=21 e=25 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=22 e=25 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=24 e=25 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=23 e=25 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=37, f=19 e=5 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1353649579233
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCalculateTimeout:346 : Timeout at 1353649579233 due in 4995 ms
>2012-11-23 05:46:14.238+000013743: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=4995
>2012-11-23 05:46:14.238+000013743: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=11 w=37
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=37 events=2
>2012-11-23 05:46:14.238+000013743: debug : virNetMessageFree:73 : msg=0x10e63f0 nfds=0 cb=(nil)
>2012-11-23 05:46:14.238+000013743: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x10e3810 tx=(nil)
>2012-11-23 05:46:14.238+000013743: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-23 05:46:14.238+000013743: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=37 events=1
>2012-11-23 05:46:14.238+000013743: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 243017824
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-23 05:46:14.238+000013743: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=21 e=25 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=22 e=25 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=24 e=25 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=23 e=25 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=37, f=19 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1353649579233
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCalculateTimeout:346 : Timeout at 1353649579233 due in 4995 ms
>2012-11-23 05:46:14.238+000013743: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=4995
>2012-11-23 05:46:14.238+000013743: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=11 w=37
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=37 events=1
>2012-11-23 05:46:14.238+000013743: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2012-11-23 05:46:14.238+000013743: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x10e3810 tx=(nil)
>2012-11-23 05:46:14.238+000013743: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-23 05:46:14.238+000013743: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=37 events=1
>2012-11-23 05:46:14.238+000013743: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 243017824
>2012-11-23 05:46:14.238+000013743: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x10e8670 len=28 prog=536903814 vers=1 proc=110 type=0 status=0 serial=3
>2012-11-23 05:46:14.238+000013743: debug : virKeepAliveCheckMessage:384 : ka=0x10e8b60, client=0x10e8670, msg=0x10e3810
>2012-11-23 05:46:14.238+000013743: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=52 frequency=5000
>2012-11-23 05:46:14.238+000013743: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 243017824
>2012-11-23 05:46:14.238+000013743: debug : virObjectRef:168 : OBJECT_REF: obj=0x10e8670
>2012-11-23 05:46:14.238+000013743: debug : virNetServerDispatchNewMessage:218 : server=0x10dacb0 client=0x10e8670 message=0x10e3810
>2012-11-23 05:46:14.238+000013743: debug : virObjectRef:168 : OBJECT_REF: obj=0x10e6040
>2012-11-23 05:46:14.238+000013743: debug : virNetMessageNew:45 : msg=0x10e4080 tracked=1
>2012-11-23 05:46:14.238+000013743: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x10e4080 tx=(nil)
>2012-11-23 05:46:14.238+000013743: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-23 05:46:14.238+000013743: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=37 events=1
>2012-11-23 05:46:14.238+000013750: debug : virNetServerHandleJob:189 : server=0x10dacb0 client=0x10e8670 message=0x10e3810 prog=0x10e6040
>2012-11-23 05:46:14.238+000013743: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 243017824
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-23 05:46:14.238+000013750: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=3 proc=110
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-23 05:46:14.238+000013750: debug : remoteDispatchGetURIHelper:7297 : server=0x10dacb0 client=0x10e8670 msg=0x10e3810 rerr=0x7fc704d29b80 args=0x7fc6f4033ba0 ret=0x7fc6f4033b30
>2012-11-23 05:46:14.238+000013743: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-23 05:46:14.238+000013750: debug : virConnectGetURI:1700 : conn=0x7fc6fc1826b0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-23 05:46:14.238+000013750: debug : virNetMessageEncodePayload:358 : Encode length as 48
>2012-11-23 05:46:14.238+000013750: debug : virNetServerClientSendMessageLocked:1116 : msg=0x10e3810 proc=110 len=48 offset=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-23 05:46:14.238+000013750: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x10e8670 len=48 prog=536903814 vers=1 proc=110 type=1 status=0 serial=3
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-23 05:46:14.238+000013750: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x10e4080 tx=0x10e3810
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-11-23 05:46:14.238+000013750: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2012-11-23 05:46:14.238+000013750: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=37 events=3
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=21 e=25 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=22 e=25 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=24 e=25 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=23 e=25 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=37, f=19 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1353649579238
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCalculateTimeout:346 : Timeout at 1353649579238 due in 5000 ms
>2012-11-23 05:46:14.238+000013743: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=5000
>2012-11-23 05:46:14.238+000013750: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-11-23 05:46:14.238+000013750: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x10e6040
>2012-11-23 05:46:14.238+000013750: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x10e8670
>2012-11-23 05:46:14.238+000013743: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=11 w=37
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-23 05:46:14.238+000013743: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=21 e=25 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=22 e=25 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=24 e=25 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=23 e=25 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=37, f=19 e=5 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1353649579238
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCalculateTimeout:346 : Timeout at 1353649579238 due in 5000 ms
>2012-11-23 05:46:14.238+000013743: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=5000
>2012-11-23 05:46:14.238+000013743: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:469 : i=11 w=37
>2012-11-23 05:46:14.238+000013743: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=37 events=2
>2012-11-23 05:46:14.238+000013743: debug : virNetMessageFree:73 : msg=0x10e3810 nfds=0 cb=(nil)
>2012-11-23 05:46:14.238+000013743: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x10e4080 tx=(nil)
>2012-11-23 05:46:14.238+000013743: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-23 05:46:14.238+000013743: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=37 events=1
>2012-11-23 05:46:14.238+000013743: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 243017824
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-23 05:46:14.238+000013743: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=21 e=25 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=22 e=25 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=24 e=25 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=23 e=25 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=37, f=19 e=1 d=0
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1353649579238
>2012-11-23 05:46:14.238+000013743: debug : virEventPollCalculateTimeout:346 : Timeout at 1353649579238 due in 5000 ms
>2012-11-23 05:46:14.238+000013743: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=5000
>2012-11-23 05:46:14.239+000013743: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=11 w=37
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=37 events=1
>2012-11-23 05:46:14.239+000013743: debug : virNetMessageDecodeLength:152 : Got length, now need 40 total (36 more)
>2012-11-23 05:46:14.239+000013743: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x10e4080 tx=(nil)
>2012-11-23 05:46:14.239+000013743: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-23 05:46:14.239+000013743: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=37 events=1
>2012-11-23 05:46:14.239+000013743: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 243017824
>2012-11-23 05:46:14.239+000013743: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x10e8670 len=40 prog=536903814 vers=1 proc=46 type=0 status=0 serial=4
>2012-11-23 05:46:14.239+000013743: debug : virKeepAliveCheckMessage:384 : ka=0x10e8b60, client=0x10e8670, msg=0x10e4080
>2012-11-23 05:46:14.239+000013743: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=52 frequency=5000
>2012-11-23 05:46:14.239+000013743: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 243017824
>2012-11-23 05:46:14.239+000013743: debug : virObjectRef:168 : OBJECT_REF: obj=0x10e8670
>2012-11-23 05:46:14.239+000013743: debug : virNetServerDispatchNewMessage:218 : server=0x10dacb0 client=0x10e8670 message=0x10e4080
>2012-11-23 05:46:14.239+000013743: debug : virObjectRef:168 : OBJECT_REF: obj=0x10e6040
>2012-11-23 05:46:14.239+000013743: debug : virNetMessageNew:45 : msg=0x10e6460 tracked=1
>2012-11-23 05:46:14.239+000013743: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x10e6460 tx=(nil)
>2012-11-23 05:46:14.239+000013743: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-23 05:46:14.239+000013745: debug : virNetServerHandleJob:189 : server=0x10dacb0 client=0x10e8670 message=0x10e4080 prog=0x10e6040
>2012-11-23 05:46:14.239+000013743: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=37 events=1
>2012-11-23 05:46:14.239+000013743: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 243017824
>2012-11-23 05:46:14.239+000013745: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=46
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-23 05:46:14.239+000013745: debug : remoteDispatchNetworkLookupByNameHelper:9132 : server=0x10dacb0 client=0x10e8670 msg=0x10e4080 rerr=0x7fc707f2eb80 args=0x7fc6f0004ce0 ret=0x7fc6f0004c70
>2012-11-23 05:46:14.239+000013743: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-23 05:46:14.239+000013745: debug : virNetworkLookupByName:10183 : conn=0x7fc6fc1826b0, name=testnet
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-23 05:46:14.239+000013745: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7fc6f0004d00 classname=virNetwork
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-23 05:46:14.239+000013745: debug : virObjectRef:168 : OBJECT_REF: obj=0x7fc6fc1826b0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-23 05:46:14.239+000013745: debug : virNetworkFree:10579 : network=0x7fc6f0004d00
>2012-11-23 05:46:14.239+000013745: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fc6f0004d00
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-11-23 05:46:14.239+000013745: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7fc6f0004d00
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-11-23 05:46:14.239+000013745: debug : virNetworkDispose:296 : release network 0x7fc6f0004d00 testnet 50dc2e11-2cd7-06e4-8b04-80d9c6649623
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=21 e=25 d=0
>2012-11-23 05:46:14.239+000013745: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7fc6fc1826b0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=22 e=25 d=0
>2012-11-23 05:46:14.239+000013745: debug : virNetMessageEncodePayload:358 : Encode length as 56
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=24 e=25 d=0
>2012-11-23 05:46:14.239+000013745: debug : virNetServerClientSendMessageLocked:1116 : msg=0x10e4080 proc=46 len=56 offset=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=23 e=25 d=0
>2012-11-23 05:46:14.239+000013745: debug : virNetServerClientSendMessageLocked:1124 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x10e8670 len=56 prog=536903814 vers=1 proc=46 type=1 status=0 serial=4
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=37, f=19 e=1 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-23 05:46:14.239+000013745: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x10e6460 tx=0x10e4080
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1353649579239
>2012-11-23 05:46:14.239+000013745: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCalculateTimeout:346 : Timeout at 1353649579239 due in 5000 ms
>2012-11-23 05:46:14.239+000013745: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=37 events=3
>2012-11-23 05:46:14.239+000013743: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=5000
>2012-11-23 05:46:14.239+000013745: debug : virEventPollInterruptLocked:701 : Interrupting
>2012-11-23 05:46:14.239+000013745: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x10e6040
>2012-11-23 05:46:14.239+000013745: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x10e8670
>2012-11-23 05:46:14.239+000013743: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=11 w=37
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-23 05:46:14.239+000013743: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=21 e=25 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=22 e=25 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=24 e=25 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=23 e=25 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=37, f=19 e=5 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1353649579239
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCalculateTimeout:346 : Timeout at 1353649579239 due in 5000 ms
>2012-11-23 05:46:14.239+000013743: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=5000
>2012-11-23 05:46:14.239+000013743: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=11 w=37
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=37 events=2
>2012-11-23 05:46:14.239+000013743: debug : virNetMessageFree:73 : msg=0x10e4080 nfds=0 cb=(nil)
>2012-11-23 05:46:14.239+000013743: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x10e6460 tx=(nil)
>2012-11-23 05:46:14.239+000013743: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-23 05:46:14.239+000013743: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=37 events=1
>2012-11-23 05:46:14.239+000013743: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 243017824
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-23 05:46:14.239+000013743: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=21 e=25 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=22 e=25 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=24 e=25 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=23 e=25 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=37, f=19 e=1 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1353649579239
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCalculateTimeout:346 : Timeout at 1353649579239 due in 5000 ms
>2012-11-23 05:46:14.239+000013743: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=5000
>2012-11-23 05:46:14.239+000013743: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:455 : Dispatch 12
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=7 w=8
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=8 w=9
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=9 w=10
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=10 w=11
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:469 : i=11 w=37
>2012-11-23 05:46:14.239+000013743: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=37 events=1
>2012-11-23 05:46:14.239+000013743: debug : virNetMessageDecodeLength:152 : Got length, now need 104 total (100 more)
>2012-11-23 05:46:14.239+000013743: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x10e6460 tx=(nil)
>2012-11-23 05:46:14.239+000013743: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-23 05:46:14.239+000013743: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=37 events=1
>2012-11-23 05:46:14.239+000013743: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 243017824
>2012-11-23 05:46:14.239+000013743: debug : virNetServerClientDispatchRead:872 : RPC_SERVER_CLIENT_MSG_RX: client=0x10e8670 len=104 prog=536903814 vers=1 proc=291 type=0 status=0 serial=5
>2012-11-23 05:46:14.239+000013743: debug : virKeepAliveCheckMessage:384 : ka=0x10e8b60, client=0x10e8670, msg=0x10e6460
>2012-11-23 05:46:14.239+000013743: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=52 frequency=5000
>2012-11-23 05:46:14.239+000013743: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 243017824
>2012-11-23 05:46:14.239+000013743: debug : virObjectRef:168 : OBJECT_REF: obj=0x10e8670
>2012-11-23 05:46:14.239+000013743: debug : virNetServerDispatchNewMessage:218 : server=0x10dacb0 client=0x10e8670 message=0x10e6460
>2012-11-23 05:46:14.239+000013743: debug : virObjectRef:168 : OBJECT_REF: obj=0x10e6040
>2012-11-23 05:46:14.239+000013743: debug : virNetMessageNew:45 : msg=0x10e3890 tracked=1
>2012-11-23 05:46:14.239+000013743: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x10e3890 tx=(nil)
>2012-11-23 05:46:14.239+000013743: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2012-11-23 05:46:14.239+000013744: debug : virNetServerHandleJob:189 : server=0x10dacb0 client=0x10e8670 message=0x10e6460 prog=0x10e6040
>2012-11-23 05:46:14.239+000013743: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=37 events=1
>2012-11-23 05:46:14.239+000013743: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 243017824
>2012-11-23 05:46:14.239+000013744: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=5 proc=291
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-23 05:46:14.239+000013744: debug : remoteDispatchNetworkUpdateHelper:9339 : server=0x10dacb0 client=0x10e8670 msg=0x10e6460 rerr=0x7fc70892fb80 args=0x7fc6ec0084b0 ret=0x7fc6ec008490
>2012-11-23 05:46:14.239+000013743: debug : virEventRunDefaultImpl:244 : running default event implementation
>2012-11-23 05:46:14.239+000013744: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7fc6ec0037f0 classname=virNetwork
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCleanupHandles:549 : Cleanup 12
>2012-11-23 05:46:14.239+000013744: debug : virObjectRef:168 : OBJECT_REF: obj=0x7fc6fc1826b0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2012-11-23 05:46:14.239+000013744: debug : virNetworkUpdate:10445 : network=0x7fc6ec0037f0, section=1, parentIndex=-1, xml=<portgroup default='no'/>, flags=0x0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=21 e=25 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=22 e=25 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=24 e=25 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=23 e=25 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollMakePollFDs:378 : Prepare n=11 w=37, f=19 e=1 d=0
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1353649579239
>2012-11-23 05:46:14.239+000013743: debug : virEventPollCalculateTimeout:346 : Timeout at 1353649579239 due in 5000 ms
>2012-11-23 05:46:14.239+000013743: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=12 timeout=5000
>
>
>     ====== end of log =====
>

Actions: View

Attachments on bug 879473: 650212