Attachment 709876 Details for Bug 921387 – Output from when it crashes when trying to remove storage pool.

Output from when it crashes when trying to remove storage pool.

libvirtd.log (text/plain), 64.06 KB, created by Brian Kruger on 2013-03-14 05:44:16 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Brian Kruger

Created: 2013-03-14 05:44:16 UTC

Size: 64.06 KB

patch

obsolete

>    ====== start of log =====
>
> : i=4 w=5
>2013-03-13 22:57:07.538+000015262: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-13 22:57:07.538+000015262: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-13 22:57:07.538+000015262: debug : virEventPollDispatchHandles:469 : i=7 w=42
>2013-03-13 22:57:07.538+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2013-03-13 22:57:07.538+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.538+000015262: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-13 22:57:07.538+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2013-03-13 22:57:07.538+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.538+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-13 22:57:07.538+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-13 22:57:07.538+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-13 22:57:07.538+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-13 22:57:07.538+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-13 22:57:07.538+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-13 22:57:07.538+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-13 22:57:07.539+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=42, f=19 e=5 d=0
>2013-03-13 22:57:07.539+000015262: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2013-03-13 22:57:07.539+000015262: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2013-03-13 22:57:07.539+000015262: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=-1
>2013-03-13 22:57:07.539+000015262: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:469 : i=7 w=42
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=42 events=2
>2013-03-13 22:57:07.539+000015262: debug : virNetMessageFree:73 : msg=0x9feb50 nfds=0 cb=(nil)
>2013-03-13 22:57:07.539+000015262: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x9ff1c0 tx=(nil)
>2013-03-13 22:57:07.539+000015262: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-13 22:57:07.539+000015262: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=42 events=1
>2013-03-13 22:57:07.539+000015262: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -920242080
>2013-03-13 22:57:07.539+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2013-03-13 22:57:07.539+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.539+000015262: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-13 22:57:07.539+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2013-03-13 22:57:07.539+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.539+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-13 22:57:07.539+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-13 22:57:07.539+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-13 22:57:07.539+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-13 22:57:07.539+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-13 22:57:07.539+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-13 22:57:07.539+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-13 22:57:07.539+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=42, f=19 e=1 d=0
>2013-03-13 22:57:07.539+000015262: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2013-03-13 22:57:07.539+000015262: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2013-03-13 22:57:07.539+000015262: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=-1
>2013-03-13 22:57:07.539+000015262: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchTimeouts:410 : Dispatch 1
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:469 : i=7 w=42
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=42 events=1
>2013-03-13 22:57:07.539+000015262: debug : virNetMessageDecodeLength:152 : Got length, now need 32 total (28 more)
>2013-03-13 22:57:07.539+000015262: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x9ff1c0 tx=(nil)
>2013-03-13 22:57:07.539+000015262: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-13 22:57:07.539+000015262: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=42 events=1
>2013-03-13 22:57:07.539+000015262: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -920242080
>2013-03-13 22:57:07.539+000015262: debug : virNetServerClientDispatchRead:875 : RPC_SERVER_CLIENT_MSG_RX: client=0x9fea70 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=1
>2013-03-13 22:57:07.539+000015262: debug : virKeepAliveCheckMessage:384 : ka=0x9fdbc0, client=0x9fea70, msg=0x9ff1c0
>2013-03-13 22:57:07.539+000015262: debug : virObjectRef:168 : OBJECT_REF: obj=0x9fea70
>2013-03-13 22:57:07.539+000015262: debug : virNetServerDispatchNewMessage:218 : server=0x9f5cb0 client=0x9fea70 message=0x9ff1c0
>2013-03-13 22:57:07.539+000015262: debug : virObjectRef:168 : OBJECT_REF: obj=0xa00fe0
>2013-03-13 22:57:07.539+000015262: debug : virNetMessageNew:45 : msg=0x9fefe0 tracked=1
>2013-03-13 22:57:07.539+000015262: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x9fefe0 tx=(nil)
>2013-03-13 22:57:07.539+000015262: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-13 22:57:07.539+000015262: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=42 events=1
>2013-03-13 22:57:07.539+000015262: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -920242080
>2013-03-13 22:57:07.539+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2013-03-13 22:57:07.539+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.539+000015262: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-13 22:57:07.539+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 1
>2013-03-13 22:57:07.539+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.539+000015268: debug : virNetServerHandleJob:189 : server=0x9f5cb0 client=0x9fea70 message=0x9ff1c0 prog=0xa00fe0
>2013-03-13 22:57:07.539+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-13 22:57:07.539+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-13 22:57:07.539+000015268: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=1 proc=60
>2013-03-13 22:57:07.539+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-13 22:57:07.539+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-13 22:57:07.539+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-13 22:57:07.539+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-13 22:57:07.539+000015268: debug : remoteDispatchSupportsFeatureHelper:13350 : server=0x9f5cb0 client=0x9fea70 msg=0x9ff1c0 rerr=0x7f5fb7913b80 args=0x7f5f9802f5a0 ret=0x7f5f980301a0
>2013-03-13 22:57:07.539+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-13 22:57:07.539+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=42, f=19 e=1 d=0
>2013-03-13 22:57:07.539+000015268: debug : virKeepAliveStart:289 : RPC_KEEPALIVE_START: ka=0x9fdbc0 client=0x9fea70 interval=0 count=0
>2013-03-13 22:57:07.539+000015262: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 1 timers
>2013-03-13 22:57:07.539+000015262: debug : virEventPollCalculateTimeout:346 : Timeout at 0 due in -1 ms
>2013-03-13 22:57:07.539+000015262: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=-1
>2013-03-13 22:57:07.539+000015268: debug : virEventPollInterruptLocked:701 : Interrupting
>2013-03-13 22:57:07.539+000015262: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-13 22:57:07.539+000015268: debug : virEventPollAddTimeout:243 : EVENT_POLL_ADD_TIMEOUT: timer=65 frequency=5000 cb=0x7f5fc8b98f70 opaque=0x9fdbc0 ff=0x41f740
>2013-03-13 22:57:07.539+000015268: debug : virObjectRef:168 : OBJECT_REF: obj=0x9fdbc0
>2013-03-13 22:57:07.539+000015268: debug : virNetMessageEncodePayload:358 : Encode length as 32
>2013-03-13 22:57:07.539+000015268: debug : virNetServerClientSendMessageLocked:1119 : msg=0x9ff1c0 proc=60 len=32 offset=0
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-13 22:57:07.539+000015268: debug : virNetServerClientSendMessageLocked:1127 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x9fea70 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=1
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-13 22:57:07.539+000015268: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x9fefe0 tx=0x9ff1c0
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-13 22:57:07.539+000015268: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2013-03-13 22:57:07.539+000015268: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=42 events=3
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-13 22:57:07.539+000015262: debug : virEventPollDispatchHandles:469 : i=7 w=42
>2013-03-13 22:57:07.539+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-13 22:57:07.539+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.539+000015268: debug : virEventPollInterruptLocked:697 : Skip interrupt, 0 -920242080
>2013-03-13 22:57:07.539+000015268: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0xa00fe0
>2013-03-13 22:57:07.539+000015268: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x9fea70
>2013-03-13 22:57:07.539+000015262: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-13 22:57:07.539+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-13 22:57:07.539+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.539+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-13 22:57:07.540+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-13 22:57:07.540+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-13 22:57:07.540+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-13 22:57:07.540+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-13 22:57:07.540+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-13 22:57:07.540+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-13 22:57:07.540+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=42, f=19 e=5 d=0
>2013-03-13 22:57:07.540+000015262: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-13 22:57:07.540+000015262: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1363215432539
>2013-03-13 22:57:07.540+000015262: debug : virEventPollCalculateTimeout:346 : Timeout at 1363215432539 due in 4999 ms
>2013-03-13 22:57:07.540+000015262: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=4999
>2013-03-13 22:57:07.540+000015262: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-13 22:57:07.540+000015262: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-13 22:57:07.540+000015262: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-13 22:57:07.540+000015262: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-13 22:57:07.540+000015262: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-13 22:57:07.540+000015262: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-13 22:57:07.540+000015262: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-13 22:57:07.540+000015262: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-13 22:57:07.540+000015262: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-13 22:57:07.540+000015262: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-13 22:57:07.540+000015262: debug : virEventPollDispatchHandles:469 : i=7 w=42
>2013-03-13 22:57:07.540+000015262: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=42 events=2
>2013-03-13 22:57:07.540+000015262: debug : virNetMessageFree:73 : msg=0x9ff1c0 nfds=0 cb=(nil)
>2013-03-13 22:57:07.540+000015262: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x9fefe0 tx=(nil)
>2013-03-13 22:57:07.540+000015262: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-13 22:57:07.540+000015262: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=42 events=1
>2013-03-13 22:57:07.540+000015262: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -920242080
>2013-03-13 22:57:07.540+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-13 22:57:07.540+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.540+000015262: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-13 22:57:07.540+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-13 22:57:07.540+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.540+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-13 22:57:07.540+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-13 22:57:07.540+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-13 22:57:07.540+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-13 22:57:07.540+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-13 22:57:07.540+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-13 22:57:07.540+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-13 22:57:07.540+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=42, f=19 e=1 d=0
>2013-03-13 22:57:07.540+000015262: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-13 22:57:07.540+000015262: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1363215432539
>2013-03-13 22:57:07.540+000015262: debug : virEventPollCalculateTimeout:346 : Timeout at 1363215432539 due in 4999 ms
>2013-03-13 22:57:07.540+000015262: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=4999
>2013-03-13 22:57:07.540+000015262: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-13 22:57:07.540+000015262: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-13 22:57:07.540+000015262: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-13 22:57:07.540+000015262: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-13 22:57:07.540+000015262: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-13 22:57:07.540+000015262: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-13 22:57:07.540+000015262: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-13 22:57:07.540+000015262: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-13 22:57:07.540+000015262: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-13 22:57:07.540+000015262: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-13 22:57:07.540+000015262: debug : virEventPollDispatchHandles:469 : i=7 w=42
>2013-03-13 22:57:07.540+000015262: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=42 events=1
>2013-03-13 22:57:07.540+000015262: debug : virNetMessageDecodeLength:152 : Got length, now need 40 total (36 more)
>2013-03-13 22:57:07.540+000015262: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x9fefe0 tx=(nil)
>2013-03-13 22:57:07.540+000015262: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-13 22:57:07.540+000015262: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=42 events=1
>2013-03-13 22:57:07.540+000015262: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -920242080
>2013-03-13 22:57:07.540+000015262: debug : virNetServerClientDispatchRead:875 : RPC_SERVER_CLIENT_MSG_RX: client=0x9fea70 len=40 prog=536903814 vers=1 proc=1 type=0 status=0 serial=2
>2013-03-13 22:57:07.540+000015262: debug : virKeepAliveCheckMessage:384 : ka=0x9fdbc0, client=0x9fea70, msg=0x9fefe0
>2013-03-13 22:57:07.540+000015262: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=65 frequency=5000
>2013-03-13 22:57:07.540+000015262: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -920242080
>2013-03-13 22:57:07.540+000015262: debug : virObjectRef:168 : OBJECT_REF: obj=0x9fea70
>2013-03-13 22:57:07.540+000015262: debug : virNetServerDispatchNewMessage:218 : server=0x9f5cb0 client=0x9fea70 message=0x9fefe0
>2013-03-13 22:57:07.540+000015262: debug : virObjectRef:168 : OBJECT_REF: obj=0xa00fe0
>2013-03-13 22:57:07.540+000015262: debug : virNetMessageNew:45 : msg=0x9fd3b0 tracked=1
>2013-03-13 22:57:07.540+000015262: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x9fd3b0 tx=(nil)
>2013-03-13 22:57:07.540+000015262: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-13 22:57:07.540+000015262: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=42 events=1
>2013-03-13 22:57:07.540+000015262: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -920242080
>2013-03-13 22:57:07.540+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-13 22:57:07.540+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.540+000015262: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-13 22:57:07.540+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-13 22:57:07.540+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.540+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-13 22:57:07.540+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-13 22:57:07.540+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-13 22:57:07.540+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-13 22:57:07.540+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-13 22:57:07.540+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-13 22:57:07.540+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-13 22:57:07.540+000015266: debug : virNetServerHandleJob:189 : server=0x9f5cb0 client=0x9fea70 message=0x9fefe0 prog=0xa00fe0
>2013-03-13 22:57:07.540+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=42, f=19 e=1 d=0
>2013-03-13 22:57:07.540+000015262: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-13 22:57:07.540+000015262: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1363215432540
>2013-03-13 22:57:07.540+000015262: debug : virEventPollCalculateTimeout:346 : Timeout at 1363215432540 due in 5000 ms
>2013-03-13 22:57:07.540+000015266: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=2 proc=1
>2013-03-13 22:57:07.540+000015262: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-13 22:57:07.540+000015266: debug : remoteDispatchOpenHelper:11127 : server=0x9f5cb0 client=0x9fea70 msg=0x9fefe0 rerr=0x7f5fb8d15b80 args=0x7f5f9c00a7f0 ret=0x7f5f9c008dd0
>2013-03-13 22:57:07.540+000015266: debug : remoteDispatchOpen:731 : priv=0x9fed10 conn=(nil)
>2013-03-13 22:57:07.540+000015266: debug : virConnectOpen:1331 : name=
>2013-03-13 22:57:07.540+000015266: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f5f9c0432b0 classname=virConnect
>2013-03-13 22:57:07.540+000015266: debug : virConnectGetConfigFile:953 : Loading config file '/etc/libvirt/libvirt.conf'
>2013-03-13 22:57:07.541+000015266: debug : virFileClose:72 : Closed fd 22
>2013-03-13 22:57:07.541+000015266: debug : do_open:1131 : no name, allowing driver auto-select
>2013-03-13 22:57:07.541+000015266: debug : do_open:1174 : trying driver 0 (Test) ...
>2013-03-13 22:57:07.541+000015266: debug : do_open:1180 : driver 0 Test returned DECLINED
>2013-03-13 22:57:07.541+000015266: debug : do_open:1174 : trying driver 1 (ESX) ...
>2013-03-13 22:57:07.541+000015266: debug : do_open:1180 : driver 1 ESX returned DECLINED
>2013-03-13 22:57:07.541+000015266: debug : do_open:1174 : trying driver 2 (remote) ...
>2013-03-13 22:57:07.541+000015266: debug : do_open:1180 : driver 2 remote returned DECLINED
>2013-03-13 22:57:07.541+000015266: debug : do_open:1174 : trying driver 3 (QEMU) ...
>2013-03-13 22:57:07.541+000015266: debug : do_open:1180 : driver 3 QEMU returned SUCCESS
>2013-03-13 22:57:07.541+000015266: debug : do_open:1202 : network driver 0 Test returned DECLINED
>2013-03-13 22:57:07.541+000015266: debug : do_open:1202 : network driver 1 ESX returned DECLINED
>2013-03-13 22:57:07.541+000015266: debug : do_open:1202 : network driver 2 remote returned DECLINED
>2013-03-13 22:57:07.541+000015266: debug : do_open:1202 : network driver 3 Network returned SUCCESS
>2013-03-13 22:57:07.541+000015266: debug : do_open:1217 : interface driver 0 Test returned DECLINED
>2013-03-13 22:57:07.541+000015266: debug : do_open:1217 : interface driver 1 ESX returned DECLINED
>2013-03-13 22:57:07.541+000015266: debug : do_open:1217 : interface driver 2 remote returned DECLINED
>2013-03-13 22:57:07.549+000015266: debug : do_open:1217 : interface driver 3 Interface returned SUCCESS
>2013-03-13 22:57:07.549+000015266: debug : do_open:1233 : storage driver 0 Test returned DECLINED
>2013-03-13 22:57:07.549+000015266: debug : do_open:1233 : storage driver 1 ESX returned DECLINED
>2013-03-13 22:57:07.549+000015266: debug : do_open:1233 : storage driver 2 remote returned DECLINED
>2013-03-13 22:57:07.549+000015266: debug : do_open:1233 : storage driver 3 storage returned SUCCESS
>2013-03-13 22:57:07.549+000015266: debug : do_open:1249 : node driver 0 Test returned DECLINED
>2013-03-13 22:57:07.549+000015266: debug : do_open:1249 : node driver 1 ESX returned DECLINED
>2013-03-13 22:57:07.549+000015266: debug : do_open:1249 : node driver 2 remote returned DECLINED
>2013-03-13 22:57:07.549+000015266: debug : do_open:1249 : node driver 3 udevDeviceMonitor returned SUCCESS
>2013-03-13 22:57:07.549+000015266: debug : do_open:1265 : secret driver 0 Test returned DECLINED
>2013-03-13 22:57:07.549+000015266: debug : do_open:1265 : secret driver 1 ESX returned DECLINED
>2013-03-13 22:57:07.549+000015266: debug : do_open:1265 : secret driver 2 remote returned DECLINED
>2013-03-13 22:57:07.549+000015266: debug : do_open:1265 : secret driver 3 secret returned SUCCESS
>2013-03-13 22:57:07.549+000015266: debug : do_open:1281 : nwfilter driver 0 Test returned DECLINED
>2013-03-13 22:57:07.549+000015266: debug : do_open:1281 : nwfilter driver 1 ESX returned DECLINED
>2013-03-13 22:57:07.549+000015266: debug : do_open:1281 : nwfilter driver 2 remote returned DECLINED
>2013-03-13 22:57:07.549+000015266: debug : do_open:1281 : nwfilter driver 3 nwfilter returned SUCCESS
>2013-03-13 22:57:07.549+000015266: debug : virNetMessageEncodePayload:358 : Encode length as 28
>2013-03-13 22:57:07.549+000015266: debug : virNetServerClientSendMessageLocked:1119 : msg=0x9fefe0 proc=1 len=28 offset=0
>2013-03-13 22:57:07.549+000015266: debug : virNetServerClientSendMessageLocked:1127 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x9fea70 len=28 prog=536903814 vers=1 proc=1 type=1 status=0 serial=2
>2013-03-13 22:57:07.549+000015266: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x9fd3b0 tx=0x9fefe0
>2013-03-13 22:57:07.549+000015266: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2013-03-13 22:57:07.549+000015266: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=42 events=3
>2013-03-13 22:57:07.549+000015266: debug : virEventPollInterruptLocked:701 : Interrupting
>2013-03-13 22:57:07.549+000015266: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0xa00fe0
>2013-03-13 22:57:07.549+000015266: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x9fea70
>2013-03-13 22:57:07.549+000015262: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-13 22:57:07.549+000015262: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-13 22:57:07.549+000015262: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-13 22:57:07.549+000015262: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-13 22:57:07.549+000015262: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2013-03-13 22:57:07.549+000015262: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-13 22:57:07.549+000015262: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-13 22:57:07.549+000015262: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-13 22:57:07.549+000015262: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-13 22:57:07.549+000015262: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-13 22:57:07.549+000015262: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-13 22:57:07.549+000015262: debug : virEventPollDispatchHandles:469 : i=7 w=42
>2013-03-13 22:57:07.549+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-13 22:57:07.549+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.549+000015262: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-13 22:57:07.549+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-13 22:57:07.549+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.549+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-13 22:57:07.549+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-13 22:57:07.549+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-13 22:57:07.549+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-13 22:57:07.549+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-13 22:57:07.549+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-13 22:57:07.549+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-13 22:57:07.549+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=42, f=19 e=5 d=0
>2013-03-13 22:57:07.549+000015262: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-13 22:57:07.549+000015262: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1363215432540
>2013-03-13 22:57:07.549+000015262: debug : virEventPollCalculateTimeout:346 : Timeout at 1363215432540 due in 4991 ms
>2013-03-13 22:57:07.549+000015262: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=4991
>2013-03-13 22:57:07.549+000015262: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-13 22:57:07.549+000015262: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-13 22:57:07.549+000015262: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-13 22:57:07.549+000015262: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-13 22:57:07.549+000015262: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-13 22:57:07.549+000015262: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-13 22:57:07.549+000015262: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-13 22:57:07.549+000015262: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-13 22:57:07.549+000015262: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-13 22:57:07.549+000015262: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-13 22:57:07.549+000015262: debug : virEventPollDispatchHandles:469 : i=7 w=42
>2013-03-13 22:57:07.549+000015262: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=42 events=2
>2013-03-13 22:57:07.549+000015262: debug : virNetMessageFree:73 : msg=0x9fefe0 nfds=0 cb=(nil)
>2013-03-13 22:57:07.549+000015262: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x9fd3b0 tx=(nil)
>2013-03-13 22:57:07.549+000015262: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-13 22:57:07.549+000015262: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=42 events=1
>2013-03-13 22:57:07.549+000015262: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -920242080
>2013-03-13 22:57:07.549+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-13 22:57:07.549+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.549+000015262: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-13 22:57:07.549+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-13 22:57:07.549+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.549+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-13 22:57:07.549+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=42, f=19 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-13 22:57:07.550+000015262: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1363215432540
>2013-03-13 22:57:07.550+000015262: debug : virEventPollCalculateTimeout:346 : Timeout at 1363215432540 due in 4990 ms
>2013-03-13 22:57:07.550+000015262: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=4990
>2013-03-13 22:57:07.550+000015262: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:469 : i=7 w=42
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=42 events=1
>2013-03-13 22:57:07.550+000015262: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2013-03-13 22:57:07.550+000015262: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x9fd3b0 tx=(nil)
>2013-03-13 22:57:07.550+000015262: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-13 22:57:07.550+000015262: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=42 events=1
>2013-03-13 22:57:07.550+000015262: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -920242080
>2013-03-13 22:57:07.550+000015262: debug : virNetServerClientDispatchRead:875 : RPC_SERVER_CLIENT_MSG_RX: client=0x9fea70 len=28 prog=536903814 vers=1 proc=110 type=0 status=0 serial=3
>2013-03-13 22:57:07.550+000015262: debug : virKeepAliveCheckMessage:384 : ka=0x9fdbc0, client=0x9fea70, msg=0x9fd3b0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=65 frequency=5000
>2013-03-13 22:57:07.550+000015262: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -920242080
>2013-03-13 22:57:07.550+000015262: debug : virObjectRef:168 : OBJECT_REF: obj=0x9fea70
>2013-03-13 22:57:07.550+000015262: debug : virNetServerDispatchNewMessage:218 : server=0x9f5cb0 client=0x9fea70 message=0x9fd3b0
>2013-03-13 22:57:07.550+000015262: debug : virObjectRef:168 : OBJECT_REF: obj=0xa00fe0
>2013-03-13 22:57:07.550+000015262: debug : virNetMessageNew:45 : msg=0x9fd420 tracked=1
>2013-03-13 22:57:07.550+000015262: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x9fd420 tx=(nil)
>2013-03-13 22:57:07.550+000015262: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-13 22:57:07.550+000015262: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=42 events=1
>2013-03-13 22:57:07.550+000015262: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -920242080
>2013-03-13 22:57:07.550+000015270: debug : virNetServerHandleJob:189 : server=0x9f5cb0 client=0x9fea70 message=0x9fd3b0 prog=0xa00fe0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-13 22:57:07.550+000015270: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=3 proc=110
>2013-03-13 22:57:07.550+000015270: debug : remoteDispatchGetURIHelper:7297 : server=0x9f5cb0 client=0x9fea70 msg=0x9fd3b0 rerr=0x7f5fb6511b80 args=0x7f5f8c008c10 ret=0x7f5f8c021560
>2013-03-13 22:57:07.550+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.550+000015270: debug : virConnectGetURI:1700 : conn=0x7f5f9c0432b0
>2013-03-13 22:57:07.550+000015262: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-13 22:57:07.550+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-13 22:57:07.550+000015270: debug : virNetMessageEncodePayload:358 : Encode length as 48
>2013-03-13 22:57:07.550+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.550+000015270: debug : virNetServerClientSendMessageLocked:1119 : msg=0x9fd3b0 proc=110 len=48 offset=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-13 22:57:07.550+000015270: debug : virNetServerClientSendMessageLocked:1127 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x9fea70 len=48 prog=536903814 vers=1 proc=110 type=1 status=0 serial=3
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-13 22:57:07.550+000015270: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x9fd420 tx=0x9fd3b0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-13 22:57:07.550+000015270: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-13 22:57:07.550+000015270: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=42 events=3
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=42, f=19 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-13 22:57:07.550+000015262: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1363215432550
>2013-03-13 22:57:07.550+000015262: debug : virEventPollCalculateTimeout:346 : Timeout at 1363215432550 due in 5000 ms
>2013-03-13 22:57:07.550+000015262: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-13 22:57:07.550+000015270: debug : virEventPollInterruptLocked:701 : Interrupting
>2013-03-13 22:57:07.550+000015262: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-13 22:57:07.550+000015270: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0xa00fe0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-13 22:57:07.550+000015270: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x9fea70
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:469 : i=7 w=42
>2013-03-13 22:57:07.550+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-13 22:57:07.550+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.550+000015262: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-13 22:57:07.550+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-13 22:57:07.550+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=42, f=19 e=5 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-13 22:57:07.550+000015262: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1363215432550
>2013-03-13 22:57:07.550+000015262: debug : virEventPollCalculateTimeout:346 : Timeout at 1363215432550 due in 5000 ms
>2013-03-13 22:57:07.550+000015262: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-13 22:57:07.550+000015262: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:469 : i=7 w=42
>2013-03-13 22:57:07.550+000015262: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=42 events=2
>2013-03-13 22:57:07.550+000015262: debug : virNetMessageFree:73 : msg=0x9fd3b0 nfds=0 cb=(nil)
>2013-03-13 22:57:07.550+000015262: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x9fd420 tx=(nil)
>2013-03-13 22:57:07.550+000015262: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-13 22:57:07.550+000015262: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=42 events=1
>2013-03-13 22:57:07.550+000015262: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -920242080
>2013-03-13 22:57:07.550+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-13 22:57:07.550+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.550+000015262: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-13 22:57:07.550+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-13 22:57:07.550+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=42, f=19 e=1 d=0
>2013-03-13 22:57:07.550+000015262: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-13 22:57:07.550+000015262: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1363215432550
>2013-03-13 22:57:07.550+000015262: debug : virEventPollCalculateTimeout:346 : Timeout at 1363215432550 due in 5000 ms
>2013-03-13 22:57:07.550+000015262: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-13 22:57:07.551+000015262: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:469 : i=7 w=42
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=42 events=1
>2013-03-13 22:57:07.551+000015262: debug : virNetMessageDecodeLength:152 : Got length, now need 40 total (36 more)
>2013-03-13 22:57:07.551+000015262: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x9fd420 tx=(nil)
>2013-03-13 22:57:07.551+000015262: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-13 22:57:07.551+000015262: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=42 events=1
>2013-03-13 22:57:07.551+000015262: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -920242080
>2013-03-13 22:57:07.551+000015262: debug : virNetServerClientDispatchRead:875 : RPC_SERVER_CLIENT_MSG_RX: client=0x9fea70 len=40 prog=536903814 vers=1 proc=84 type=0 status=0 serial=4
>2013-03-13 22:57:07.551+000015262: debug : virKeepAliveCheckMessage:384 : ka=0x9fdbc0, client=0x9fea70, msg=0x9fd420
>2013-03-13 22:57:07.551+000015262: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=65 frequency=5000
>2013-03-13 22:57:07.551+000015262: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -920242080
>2013-03-13 22:57:07.551+000015262: debug : virObjectRef:168 : OBJECT_REF: obj=0x9fea70
>2013-03-13 22:57:07.551+000015262: debug : virNetServerDispatchNewMessage:218 : server=0x9f5cb0 client=0x9fea70 message=0x9fd420
>2013-03-13 22:57:07.551+000015262: debug : virObjectRef:168 : OBJECT_REF: obj=0xa00fe0
>2013-03-13 22:57:07.551+000015262: debug : virNetMessageNew:45 : msg=0x9fd500 tracked=1
>2013-03-13 22:57:07.551+000015262: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x9fd500 tx=(nil)
>2013-03-13 22:57:07.551+000015262: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-13 22:57:07.551+000015262: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=42 events=1
>2013-03-13 22:57:07.551+000015262: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -920242080
>2013-03-13 22:57:07.551+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-13 22:57:07.551+000015263: debug : virNetServerHandleJob:189 : server=0x9f5cb0 client=0x9fea70 message=0x9fd420 prog=0xa00fe0
>2013-03-13 22:57:07.551+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.551+000015262: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-13 22:57:07.551+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-13 22:57:07.551+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.551+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-13 22:57:07.551+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-13 22:57:07.551+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-13 22:57:07.551+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-13 22:57:07.551+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-13 22:57:07.551+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-13 22:57:07.551+000015263: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=4 proc=84
>2013-03-13 22:57:07.551+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-13 22:57:07.551+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=42, f=19 e=1 d=0
>2013-03-13 22:57:07.551+000015262: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-13 22:57:07.551+000015262: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1363215432551
>2013-03-13 22:57:07.551+000015262: debug : virEventPollCalculateTimeout:346 : Timeout at 1363215432551 due in 5000 ms
>2013-03-13 22:57:07.551+000015263: debug : remoteDispatchStoragePoolLookupByNameHelper:12174 : server=0x9f5cb0 client=0x9fea70 msg=0x9fd420 rerr=0x7f5fbab18b80 args=0x7f5fac15d340 ret=0x7f5fac13cb10
>2013-03-13 22:57:07.551+000015262: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-13 22:57:07.551+000015263: debug : virStoragePoolLookupByName:12107 : conn=0x7f5f9c0432b0, name=vg001
>2013-03-13 22:57:07.551+000015263: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f5fac0082b0 classname=virStoragePool
>2013-03-13 22:57:07.551+000015263: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f5f9c0432b0
>2013-03-13 22:57:07.551+000015263: debug : virStoragePoolFree:12587 : pool=0x7f5fac0082b0
>2013-03-13 22:57:07.551+000015263: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f5fac0082b0
>2013-03-13 22:57:07.551+000015263: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f5fac0082b0
>2013-03-13 22:57:07.551+000015263: debug : virStoragePoolDispose:443 : release pool 0x7f5fac0082b0 vg001 1c55baf4-00cb-30cd-52cb-3a075d654b2d
>2013-03-13 22:57:07.551+000015263: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f5f9c0432b0
>2013-03-13 22:57:07.551+000015263: debug : virNetMessageEncodePayload:358 : Encode length as 56
>2013-03-13 22:57:07.551+000015263: debug : virNetServerClientSendMessageLocked:1119 : msg=0x9fd420 proc=84 len=56 offset=0
>2013-03-13 22:57:07.551+000015263: debug : virNetServerClientSendMessageLocked:1127 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x9fea70 len=56 prog=536903814 vers=1 proc=84 type=1 status=0 serial=4
>2013-03-13 22:57:07.551+000015263: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x9fd500 tx=0x9fd420
>2013-03-13 22:57:07.551+000015263: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2013-03-13 22:57:07.551+000015263: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=42 events=3
>2013-03-13 22:57:07.551+000015263: debug : virEventPollInterruptLocked:701 : Interrupting
>2013-03-13 22:57:07.551+000015263: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0xa00fe0
>2013-03-13 22:57:07.551+000015263: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x9fea70
>2013-03-13 22:57:07.551+000015262: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:469 : i=7 w=42
>2013-03-13 22:57:07.551+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-13 22:57:07.551+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.551+000015262: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-13 22:57:07.551+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-13 22:57:07.551+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.551+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-13 22:57:07.551+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-13 22:57:07.551+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-13 22:57:07.551+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-13 22:57:07.551+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-13 22:57:07.551+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-13 22:57:07.551+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-13 22:57:07.551+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=42, f=19 e=5 d=0
>2013-03-13 22:57:07.551+000015262: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-13 22:57:07.551+000015262: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1363215432551
>2013-03-13 22:57:07.551+000015262: debug : virEventPollCalculateTimeout:346 : Timeout at 1363215432551 due in 5000 ms
>2013-03-13 22:57:07.551+000015262: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-13 22:57:07.551+000015262: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:469 : i=7 w=42
>2013-03-13 22:57:07.551+000015262: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=42 events=2
>2013-03-13 22:57:07.551+000015262: debug : virNetMessageFree:73 : msg=0x9fd420 nfds=0 cb=(nil)
>2013-03-13 22:57:07.551+000015262: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x9fd500 tx=(nil)
>2013-03-13 22:57:07.551+000015262: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-13 22:57:07.551+000015262: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=42 events=1
>2013-03-13 22:57:07.551+000015262: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -920242080
>2013-03-13 22:57:07.551+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-13 22:57:07.551+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.551+000015262: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-13 22:57:07.551+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-13 22:57:07.551+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.551+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-13 22:57:07.551+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-13 22:57:07.551+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-13 22:57:07.551+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-13 22:57:07.551+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-13 22:57:07.551+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-13 22:57:07.551+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-13 22:57:07.551+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=42, f=19 e=1 d=0
>2013-03-13 22:57:07.551+000015262: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-13 22:57:07.552+000015262: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1363215432551
>2013-03-13 22:57:07.552+000015262: debug : virEventPollCalculateTimeout:346 : Timeout at 1363215432551 due in 4999 ms
>2013-03-13 22:57:07.552+000015262: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=4999
>2013-03-13 22:57:07.552+000015262: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-13 22:57:07.552+000015262: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-13 22:57:07.552+000015262: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-13 22:57:07.552+000015262: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-13 22:57:07.552+000015262: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-13 22:57:07.552+000015262: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-13 22:57:07.552+000015262: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-13 22:57:07.552+000015262: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-13 22:57:07.552+000015262: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-13 22:57:07.552+000015262: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-13 22:57:07.552+000015262: debug : virEventPollDispatchHandles:469 : i=7 w=42
>2013-03-13 22:57:07.552+000015262: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=42 events=1
>2013-03-13 22:57:07.552+000015262: debug : virNetMessageDecodeLength:152 : Got length, now need 60 total (56 more)
>2013-03-13 22:57:07.552+000015262: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x9fd500 tx=(nil)
>2013-03-13 22:57:07.552+000015262: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-13 22:57:07.552+000015262: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=42 events=1
>2013-03-13 22:57:07.552+000015262: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -920242080
>2013-03-13 22:57:07.552+000015262: debug : virNetServerClientDispatchRead:875 : RPC_SERVER_CLIENT_MSG_RX: client=0x9fea70 len=60 prog=536903814 vers=1 proc=81 type=0 status=0 serial=5
>2013-03-13 22:57:07.552+000015262: debug : virKeepAliveCheckMessage:384 : ka=0x9fdbc0, client=0x9fea70, msg=0x9fd500
>2013-03-13 22:57:07.552+000015262: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=65 frequency=5000
>2013-03-13 22:57:07.552+000015262: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -920242080
>2013-03-13 22:57:07.552+000015262: debug : virObjectRef:168 : OBJECT_REF: obj=0x9fea70
>2013-03-13 22:57:07.552+000015262: debug : virNetServerDispatchNewMessage:218 : server=0x9f5cb0 client=0x9fea70 message=0x9fd500
>2013-03-13 22:57:07.552+000015262: debug : virObjectRef:168 : OBJECT_REF: obj=0xa00fe0
>2013-03-13 22:57:07.552+000015262: debug : virNetMessageNew:45 : msg=0x9fefe0 tracked=1
>2013-03-13 22:57:07.552+000015262: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x9fefe0 tx=(nil)
>2013-03-13 22:57:07.552+000015262: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-13 22:57:07.552+000015262: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=42 events=1
>2013-03-13 22:57:07.552+000015262: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 -920242080
>2013-03-13 22:57:07.552+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-13 22:57:07.552+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.552+000015262: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-13 22:57:07.552+000015262: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-13 22:57:07.552+000015262: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-13 22:57:07.552+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-13 22:57:07.552+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-13 22:57:07.552+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-13 22:57:07.552+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-13 22:57:07.552+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-13 22:57:07.552+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-13 22:57:07.552+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-13 22:57:07.552+000015262: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=42, f=19 e=1 d=0
>2013-03-13 22:57:07.552+000015264: debug : virNetServerHandleJob:189 : server=0x9f5cb0 client=0x9fea70 message=0x9fd500 prog=0xa00fe0
>2013-03-13 22:57:07.552+000015262: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-13 22:57:07.552+000015262: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1363215432552
>2013-03-13 22:57:07.552+000015264: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=5 proc=81
>2013-03-13 22:57:07.552+000015262: debug : virEventPollCalculateTimeout:346 : Timeout at 1363215432552 due in 5000 ms
>2013-03-13 22:57:07.552+000015262: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-13 22:57:07.552+000015264: debug : remoteDispatchStoragePoolDeleteHelper:11694 : server=0x9f5cb0 client=0x9fea70 msg=0x9fd500 rerr=0x7f5fba117b80 args=0x7f5f9400a730 ret=0x7f5f94008d20
>2013-03-13 22:57:07.552+000015264: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f5f9400a6f0 classname=virStoragePool
>2013-03-13 22:57:07.552+000015264: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f5f9c0432b0
>2013-03-13 22:57:07.552+000015264: debug : virStoragePoolDelete:12543 : pool=0x7f5f9400a6f0, flags=0
>2013-03-13 22:57:07.553+000015264: debug : virCommandRunAsync:2209 : About to run /sbin/vgremove -f vg001
>2013-03-13 22:57:07.554+000015264: debug : virFileClose:72 : Closed fd 24
>2013-03-13 22:57:07.554+000015264: debug : virFileClose:72 : Closed fd 26
>2013-03-13 22:57:07.554+000015264: debug : virFileClose:72 : Closed fd 28
>2013-03-13 22:57:07.554+000015264: debug : virCommandRunAsync:2227 : Command result 0, with PID 16632
>2013-03-13 22:57:07.594+000015264: debug : virCommandRun:2025 : Result status 0, stdout: '  Volume group "vg001" successfully removed
>' stderr: ''
>2013-03-13 22:57:07.595+000015264: debug : virFileClose:72 : Closed fd 25
>2013-03-13 22:57:07.595+000015264: debug : virFileClose:72 : Closed fd 27
>2013-03-13 22:57:07.595+000015264: debug : virFileClose:72 : Closed fd 0
>2013-03-13 22:57:07.595+000015264: warning : virFileClose:65 : Tried to close invalid fd 0
>
>
>     ====== end of log =====
>

Actions: View

Attachments on bug 921387: 709876 | 915683