Attachment 915683 Details for Bug 921387 – Comment

Comment

comment_5635717.txt (text/plain), 129.27 KB, created by Brian Kruger on 2013-03-26 18:54:00 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Brian Kruger

Created: 2013-03-26 18:54:00 UTC

Size: 129.27 KB

patch

obsolete

>So we're a little closer.  Removing the pool still crashes, but it does indeed remove the volume group.
>
>
>testbox (Linux_2.6.32) $ sudo yum list installed | grep libvirt
>libvirt.x86_64                   0.10.2-18.el6_4.2        @updates
>libvirt-client.x86_64            0.10.2-18.el6_4.2        @updates
>libvirt-python.x86_64            0.10.2-18.el6_4.2        @updates
>
>
>testbox (Linux_2.6.32) $ sudo virsh
>
>
>virsh # pool-list --all
>Name                 State      Autostart
>-----------------------------------------
>vg001                inactive   no
>
>virsh # pool-delete vg001
>error: Failed to delete pool vg001
>error: End of file while reading data: Input/output error
>error: Failed to reconnect to the hypervisor
>
>virsh # ^Z
>
>testbox (Linux_2.6.32) $ sudo /etc/init.d/libvirtd start
>Starting libvirtd daemon:                                  [  OK  ]
>testbox (Linux_2.6.32) $ fg
> 
>virsh # pool-delete vg001
>error: Failed to delete pool vg001
>error: internal error Child process (/sbin/vgremove -f vg001) unexpected exit status 5:   Volume group "vg001" not found
>
>
>
>Log--
>
>Caught abort signal dumping internal log buffer:
>
>
>    ====== start of log =====
>
>rProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=6 proc=89
>2013-03-26 18:44:23.452+00007026: debug : remoteDispatchStoragePoolGetAutostartHelper:11799 : server=0x13fecb0 client=0x1405060 msg=0x1406bc0 rerr=0x7f002dcbdb80 args=0x7f001800a900 ret=0x7f0018008ee0
>2013-03-26 18:44:23.452+00007026: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f001800a610 classname=virStoragePool
>2013-03-26 18:44:23.452+00007026: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f0024107070
>2013-03-26 18:44:23.452+00007026: debug : virStoragePoolGetAutostart:12879 : pool=0x7f001800a610, autostart=0x7f002dcbdadc
>2013-03-26 18:44:23.452+00007026: debug : virStoragePoolFree:12587 : pool=0x7f001800a610
>2013-03-26 18:44:23.452+00007026: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f001800a610
>2013-03-26 18:44:23.452+00007026: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f001800a610
>2013-03-26 18:44:23.452+00007026: debug : virStoragePoolDispose:443 : release pool 0x7f001800a610 vg001 57cae153-f029-e00c-5778-fed28a84fcda
>2013-03-26 18:44:23.452+00007026: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f0024107070
>2013-03-26 18:44:23.452+00007026: debug : virNetMessageEncodePayload:358 : Encode length as 32
>2013-03-26 18:44:23.452+00007026: debug : virNetServerClientSendMessageLocked:1119 : msg=0x1406bc0 proc=89 len=32 offset=0
>2013-03-26 18:44:23.452+00007026: debug : virNetServerClientSendMessageLocked:1127 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1405060 len=32 prog=536903814 vers=1 proc=89 type=1 status=0 serial=6
>2013-03-26 18:44:23.452+00007026: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1405140 tx=0x1406bc0
>2013-03-26 18:44:23.452+00007026: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2013-03-26 18:44:23.452+00007026: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=3
>2013-03-26 18:44:23.452+00007026: debug : virEventPollInterruptLocked:701 : Interrupting
>2013-03-26 18:44:23.452+00007026: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1409fe0
>2013-03-26 18:44:23.452+00007026: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1405060
>2013-03-26 18:44:23.452+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:23.452+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.452+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.452+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:23.452+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.452+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.452+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:23.452+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:23.452+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:23.452+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:23.452+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:23.452+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:23.452+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:23.452+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=5 d=0
>2013-03-26 18:44:23.452+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:23.452+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323468452
>2013-03-26 18:44:23.452+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323468452 due in 5000 ms
>2013-03-26 18:44:23.452+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-26 18:44:23.452+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2
>2013-03-26 18:44:23.452+00007017: debug : virNetMessageFree:73 : msg=0x1406bc0 nfds=0 cb=(nil)
>2013-03-26 18:44:23.452+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1405140 tx=(nil)
>2013-03-26 18:44:23.452+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.453+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323468452
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323468452 due in 4999 ms
>2013-03-26 18:44:23.453+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=4999
>2013-03-26 18:44:23.453+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1
>2013-03-26 18:44:23.453+00007017: debug : virNetMessageDecodeLength:152 : Got length, now need 56 total (52 more)
>2013-03-26 18:44:23.453+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1405140 tx=(nil)
>2013-03-26 18:44:23.453+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:23.453+00007017: debug : virNetServerClientDispatchRead:875 : RPC_SERVER_CLIENT_MSG_RX: client=0x1405060 len=56 prog=536903814 vers=1 proc=87 type=0 status=0 serial=7
>2013-03-26 18:44:23.453+00007017: debug : virKeepAliveCheckMessage:384 : ka=0x14054e0, client=0x1405060, msg=0x1405140
>2013-03-26 18:44:23.453+00007017: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=10 frequency=5000
>2013-03-26 18:44:23.453+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:23.453+00007017: debug : virObjectRef:168 : OBJECT_REF: obj=0x1405060
>2013-03-26 18:44:23.453+00007017: debug : virNetServerDispatchNewMessage:218 : server=0x13fecb0 client=0x1405060 message=0x1405140
>2013-03-26 18:44:23.453+00007017: debug : virObjectRef:168 : OBJECT_REF: obj=0x1409fe0
>2013-03-26 18:44:23.453+00007017: debug : virNetMessageNew:45 : msg=0x1405820 tracked=1
>2013-03-26 18:44:23.453+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1405820 tx=(nil)
>2013-03-26 18:44:23.453+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:23.453+00007023: debug : virNetServerHandleJob:189 : server=0x13fecb0 client=0x1405060 message=0x1405140 prog=0x1409fe0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:23.453+00007023: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=7 proc=87
>2013-03-26 18:44:23.453+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.453+00007023: debug : remoteDispatchStoragePoolGetInfoHelper:11855 : server=0x13fecb0 client=0x1405060 msg=0x1405140 rerr=0x7f002fac0b80 args=0x7f00241436e0 ret=0x7f0024143740
>2013-03-26 18:44:23.453+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.453+00007023: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f0024143700 classname=virStoragePool
>2013-03-26 18:44:23.453+00007023: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f0024107070
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:23.453+00007023: debug : virStoragePoolGetInfo:12789 : pool=0x7f0024143700, info=0x7f002fac0ac0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:23.453+00007023: debug : virStoragePoolFree:12587 : pool=0x7f0024143700
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:23.453+00007023: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f0024143700
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:23.453+00007023: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f0024143700
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:23.453+00007023: debug : virStoragePoolDispose:443 : release pool 0x7f0024143700 vg001 57cae153-f029-e00c-5778-fed28a84fcda
>2013-03-26 18:44:23.453+00007023: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f0024107070
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=1 d=0
>2013-03-26 18:44:23.453+00007023: debug : virNetMessageEncodePayload:358 : Encode length as 56
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:23.453+00007023: debug : virNetServerClientSendMessageLocked:1119 : msg=0x1405140 proc=87 len=56 offset=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323468453
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323468453 due in 5000 ms
>2013-03-26 18:44:23.453+00007023: debug : virNetServerClientSendMessageLocked:1127 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1405060 len=56 prog=536903814 vers=1 proc=87 type=1 status=0 serial=7
>2013-03-26 18:44:23.453+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-26 18:44:23.453+00007023: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1405820 tx=0x1405140
>2013-03-26 18:44:23.453+00007023: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2013-03-26 18:44:23.453+00007023: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=3
>2013-03-26 18:44:23.453+00007023: debug : virEventPollInterruptLocked:701 : Interrupting
>2013-03-26 18:44:23.453+00007023: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1409fe0
>2013-03-26 18:44:23.453+00007023: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1405060
>2013-03-26 18:44:23.453+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.453+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=5 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323468453
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323468453 due in 5000 ms
>2013-03-26 18:44:23.453+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-26 18:44:23.453+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2
>2013-03-26 18:44:23.453+00007017: debug : virNetMessageFree:73 : msg=0x1405140 nfds=0 cb=(nil)
>2013-03-26 18:44:23.453+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1405820 tx=(nil)
>2013-03-26 18:44:23.453+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.453+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323468453
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323468453 due in 5000 ms
>2013-03-26 18:44:23.453+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-26 18:44:23.453+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1
>2013-03-26 18:44:23.453+00007017: debug : virNetMessageDecodeLength:152 : Got length, now need 56 total (52 more)
>2013-03-26 18:44:23.453+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1405820 tx=(nil)
>2013-03-26 18:44:23.453+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:23.453+00007017: debug : virNetServerClientDispatchRead:875 : RPC_SERVER_CLIENT_MSG_RX: client=0x1405060 len=56 prog=536903814 vers=1 proc=154 type=0 status=0 serial=8
>2013-03-26 18:44:23.453+00007017: debug : virKeepAliveCheckMessage:384 : ka=0x14054e0, client=0x1405060, msg=0x1405820
>2013-03-26 18:44:23.453+00007017: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=10 frequency=5000
>2013-03-26 18:44:23.453+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:23.453+00007017: debug : virObjectRef:168 : OBJECT_REF: obj=0x1405060
>2013-03-26 18:44:23.453+00007017: debug : virNetServerDispatchNewMessage:218 : server=0x13fecb0 client=0x1405060 message=0x1405820
>2013-03-26 18:44:23.453+00007017: debug : virObjectRef:168 : OBJECT_REF: obj=0x1409fe0
>2013-03-26 18:44:23.453+00007017: debug : virNetMessageNew:45 : msg=0x1407290 tracked=1
>2013-03-26 18:44:23.453+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1407290 tx=(nil)
>2013-03-26 18:44:23.453+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.453+00007024: debug : virNetServerHandleJob:189 : server=0x13fecb0 client=0x1405060 message=0x1405820 prog=0x1409fe0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.453+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:23.453+00007024: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=8 proc=154
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:23.454+00007024: debug : remoteDispatchStoragePoolIsActiveHelper:11970 : server=0x13fecb0 client=0x1405060 msg=0x1405820 rerr=0x7f002f0bfb80 args=0x7f00040008c0 ret=0x7f0004000c50
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:23.454+00007024: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f0004000c10 classname=virStoragePool
>2013-03-26 18:44:23.454+00007024: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f0024107070
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:23.454+00007024: debug : virStoragePoolIsActive:16336 : pool=0x7f0004000c10
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:23.454+00007024: debug : virStoragePoolFree:12587 : pool=0x7f0004000c10
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:23.454+00007024: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f0004000c10
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=1 d=0
>2013-03-26 18:44:23.454+00007024: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f0004000c10
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323468453
>2013-03-26 18:44:23.454+00007024: debug : virStoragePoolDispose:443 : release pool 0x7f0004000c10 vg001 57cae153-f029-e00c-5778-fed28a84fcda
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323468453 due in 4999 ms
>2013-03-26 18:44:23.454+00007024: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f0024107070
>2013-03-26 18:44:23.454+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=4999
>2013-03-26 18:44:23.454+00007024: debug : virNetMessageEncodePayload:358 : Encode length as 32
>2013-03-26 18:44:23.454+00007024: debug : virNetServerClientSendMessageLocked:1119 : msg=0x1405820 proc=154 len=32 offset=0
>2013-03-26 18:44:23.454+00007024: debug : virNetServerClientSendMessageLocked:1127 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1405060 len=32 prog=536903814 vers=1 proc=154 type=1 status=0 serial=8
>2013-03-26 18:44:23.454+00007024: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1407290 tx=0x1405820
>2013-03-26 18:44:23.454+00007024: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2013-03-26 18:44:23.454+00007024: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=3
>2013-03-26 18:44:23.454+00007024: debug : virEventPollInterruptLocked:701 : Interrupting
>2013-03-26 18:44:23.454+00007024: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1409fe0
>2013-03-26 18:44:23.454+00007024: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1405060
>2013-03-26 18:44:23.454+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.454+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=5 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323468453
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323468453 due in 4999 ms
>2013-03-26 18:44:23.454+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=4999
>2013-03-26 18:44:23.454+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2
>2013-03-26 18:44:23.454+00007017: debug : virNetMessageFree:73 : msg=0x1405820 nfds=0 cb=(nil)
>2013-03-26 18:44:23.454+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1407290 tx=(nil)
>2013-03-26 18:44:23.454+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:23.454+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:23.454+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.454+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323468453
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323468453 due in 4999 ms
>2013-03-26 18:44:23.454+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=4999
>2013-03-26 18:44:28.458+00007017: debug : virEventPollRunOnce:625 : Poll got 0 event(s)
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchTimeouts:433 : EVENT_POLL_DISPATCH_TIMEOUT: timer=10
>2013-03-26 18:44:28.458+00007017: debug : virKeepAliveTimerInternal:148 : RPC_KEEPALIVE_TIMEOUT: ka=0x14054e0 client=0x1405060 countToDeath=5 idle=5
>2013-03-26 18:44:28.458+00007017: debug : virNetMessageNew:45 : msg=0x1407300 tracked=0
>2013-03-26 18:44:28.458+00007017: debug : virNetMessageEncodePayloadEmpty:448 : Encode length as 28
>2013-03-26 18:44:28.458+00007017: debug : virKeepAliveMessage:116 : Sending keepalive request to client 0x1405060
>2013-03-26 18:44:28.458+00007017: debug : virKeepAliveMessage:119 : RPC_KEEPALIVE_SEND: ka=0x14054e0 client=0x1405060 prog=1801807216 vers=1 proc=1
>2013-03-26 18:44:28.458+00007017: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=10 frequency=5000
>2013-03-26 18:44:28.458+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:28.458+00007017: debug : virObjectRef:168 : OBJECT_REF: obj=0x14054e0
>2013-03-26 18:44:28.458+00007017: debug : virNetServerClientSendMessageLocked:1119 : msg=0x1407300 proc=1 len=28 offset=0
>2013-03-26 18:44:28.458+00007017: debug : virNetServerClientSendMessageLocked:1127 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1405060 len=28 prog=1801807216 vers=1 proc=1 type=2 status=0 serial=0
>2013-03-26 18:44:28.458+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1407290 tx=0x1407300
>2013-03-26 18:44:28.458+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2013-03-26 18:44:28.458+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=3
>2013-03-26 18:44:28.458+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:28.458+00007017: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x14054e0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:28.458+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=5 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323473458
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323473458 due in 5000 ms
>2013-03-26 18:44:28.458+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-26 18:44:28.458+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2
>2013-03-26 18:44:28.458+00007017: debug : virNetMessageFree:73 : msg=0x1407300 nfds=0 cb=(nil)
>2013-03-26 18:44:28.458+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1407290 tx=(nil)
>2013-03-26 18:44:28.458+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:28.458+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:28.458+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:28.458+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323473458
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323473458 due in 5000 ms
>2013-03-26 18:44:28.458+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-26 18:44:28.459+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:28.459+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:28.459+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:28.459+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:28.459+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:28.459+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:28.459+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:28.459+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:28.459+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:28.459+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:28.459+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:28.459+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1
>2013-03-26 18:44:28.459+00007017: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2013-03-26 18:44:28.459+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1407290 tx=(nil)
>2013-03-26 18:44:28.459+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:28.459+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:28.459+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:28.459+00007017: debug : virNetServerClientDispatchRead:875 : RPC_SERVER_CLIENT_MSG_RX: client=0x1405060 len=28 prog=1801807216 vers=1 proc=2 type=2 status=0 serial=0
>2013-03-26 18:44:28.459+00007017: debug : virKeepAliveCheckMessage:384 : ka=0x14054e0, client=0x1405060, msg=0x1407290
>2013-03-26 18:44:28.459+00007017: debug : virKeepAliveCheckMessage:401 : RPC_KEEPALIVE_RECEIVED: ka=0x14054e0 client=0x1405060 prog=1801807216 vers=1 proc=2
>2013-03-26 18:44:28.459+00007017: debug : virKeepAliveCheckMessage:410 : Got keepalive response from client 0x1405060
>2013-03-26 18:44:28.459+00007017: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=10 frequency=5000
>2013-03-26 18:44:28.459+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:28.459+00007017: debug : virNetMessageFree:73 : msg=0x1407290 nfds=0 cb=(nil)
>2013-03-26 18:44:28.459+00007017: debug : virNetMessageNew:45 : msg=0x1407290 tracked=1
>2013-03-26 18:44:28.459+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1407290 tx=(nil)
>2013-03-26 18:44:28.459+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:28.459+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:28.459+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:28.459+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:28.459+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:28.459+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:28.459+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:28.459+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:28.459+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:28.459+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:28.459+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:28.459+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:28.459+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:28.459+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:28.459+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:28.459+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=1 d=0
>2013-03-26 18:44:28.459+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:28.459+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323473459
>2013-03-26 18:44:28.459+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323473459 due in 5000 ms
>2013-03-26 18:44:28.459+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-26 18:44:31.155+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1
>2013-03-26 18:44:31.155+00007017: debug : virNetMessageDecodeLength:152 : Got length, now need 40 total (36 more)
>2013-03-26 18:44:31.155+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1407290 tx=(nil)
>2013-03-26 18:44:31.155+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:31.155+00007017: debug : virNetServerClientDispatchRead:875 : RPC_SERVER_CLIENT_MSG_RX: client=0x1405060 len=40 prog=536903814 vers=1 proc=84 type=0 status=0 serial=9
>2013-03-26 18:44:31.155+00007017: debug : virKeepAliveCheckMessage:384 : ka=0x14054e0, client=0x1405060, msg=0x1407290
>2013-03-26 18:44:31.155+00007017: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=10 frequency=5000
>2013-03-26 18:44:31.155+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:31.155+00007017: debug : virObjectRef:168 : OBJECT_REF: obj=0x1405060
>2013-03-26 18:44:31.155+00007017: debug : virNetServerDispatchNewMessage:218 : server=0x13fecb0 client=0x1405060 message=0x1407290
>2013-03-26 18:44:31.155+00007017: debug : virObjectRef:168 : OBJECT_REF: obj=0x1409fe0
>2013-03-26 18:44:31.155+00007017: debug : virNetMessageNew:45 : msg=0x1406b50 tracked=1
>2013-03-26 18:44:31.155+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1406b50 tx=(nil)
>2013-03-26 18:44:31.155+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:31.155+00007022: debug : virNetServerHandleJob:189 : server=0x13fecb0 client=0x1405060 message=0x1407290 prog=0x1409fe0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:31.155+00007022: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=9 proc=84
>2013-03-26 18:44:31.155+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:31.155+00007022: debug : remoteDispatchStoragePoolLookupByNameHelper:12174 : server=0x13fecb0 client=0x1405060 msg=0x1407290 rerr=0x7f00304c1b80 args=0x7f00200f0fe0 ret=0x7f00200f1ef0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:31.155+00007022: debug : virStoragePoolLookupByName:12107 : conn=0x7f0024107070, name=vg001
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:31.155+00007022: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f00200f11a0 classname=virStoragePool
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:31.155+00007022: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f0024107070
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:31.155+00007022: debug : virStoragePoolFree:12587 : pool=0x7f00200f11a0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:31.155+00007022: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f00200f11a0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:31.155+00007022: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f00200f11a0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=1 d=0
>2013-03-26 18:44:31.155+00007022: debug : virStoragePoolDispose:443 : release pool 0x7f00200f11a0 vg001 57cae153-f029-e00c-5778-fed28a84fcda
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:31.155+00007022: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f0024107070
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323476155
>2013-03-26 18:44:31.155+00007022: debug : virNetMessageEncodePayload:358 : Encode length as 56
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323476155 due in 5000 ms
>2013-03-26 18:44:31.155+00007022: debug : virNetServerClientSendMessageLocked:1119 : msg=0x1407290 proc=84 len=56 offset=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-26 18:44:31.155+00007022: debug : virNetServerClientSendMessageLocked:1127 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1405060 len=56 prog=536903814 vers=1 proc=84 type=1 status=0 serial=9
>2013-03-26 18:44:31.155+00007022: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1406b50 tx=0x1407290
>2013-03-26 18:44:31.155+00007022: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2013-03-26 18:44:31.155+00007022: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=3
>2013-03-26 18:44:31.155+00007022: debug : virEventPollInterruptLocked:701 : Interrupting
>2013-03-26 18:44:31.155+00007022: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1409fe0
>2013-03-26 18:44:31.155+00007022: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1405060
>2013-03-26 18:44:31.155+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:31.155+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=5 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323476155
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323476155 due in 5000 ms
>2013-03-26 18:44:31.155+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-26 18:44:31.155+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2
>2013-03-26 18:44:31.155+00007017: debug : virNetMessageFree:73 : msg=0x1407290 nfds=0 cb=(nil)
>2013-03-26 18:44:31.155+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1406b50 tx=(nil)
>2013-03-26 18:44:31.155+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:31.155+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323476155
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323476155 due in 5000 ms
>2013-03-26 18:44:31.155+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-26 18:44:31.155+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1
>2013-03-26 18:44:31.155+00007017: debug : virNetMessageDecodeLength:152 : Got length, now need 60 total (56 more)
>2013-03-26 18:44:31.155+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1406b50 tx=(nil)
>2013-03-26 18:44:31.155+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:31.155+00007017: debug : virNetServerClientDispatchRead:875 : RPC_SERVER_CLIENT_MSG_RX: client=0x1405060 len=60 prog=536903814 vers=1 proc=81 type=0 status=0 serial=10
>2013-03-26 18:44:31.155+00007017: debug : virKeepAliveCheckMessage:384 : ka=0x14054e0, client=0x1405060, msg=0x1406b50
>2013-03-26 18:44:31.155+00007017: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=10 frequency=5000
>2013-03-26 18:44:31.155+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:31.155+00007017: debug : virObjectRef:168 : OBJECT_REF: obj=0x1405060
>2013-03-26 18:44:31.156+00007017: debug : virNetServerDispatchNewMessage:218 : server=0x13fecb0 client=0x1405060 message=0x1406b50
>2013-03-26 18:44:31.156+00007017: debug : virObjectRef:168 : OBJECT_REF: obj=0x1409fe0
>2013-03-26 18:44:31.156+00007017: debug : virNetMessageNew:45 : msg=0x1405900 tracked=1
>2013-03-26 18:44:31.156+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1405900 tx=(nil)
>2013-03-26 18:44:31.156+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:31.156+00007020: debug : virNetServerHandleJob:189 : server=0x13fecb0 client=0x1405060 message=0x1406b50 prog=0x1409fe0
>2013-03-26 18:44:31.156+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:31.156+00007020: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=10 proc=81
>2013-03-26 18:44:31.156+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:31.156+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:31.156+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:31.156+00007020: debug : remoteDispatchStoragePoolDeleteHelper:11694 : server=0x13fecb0 client=0x1405060 msg=0x1406b50 rerr=0x7f00318c3b80 args=0x7f0014000c70 ret=0x7f0014000b20
>2013-03-26 18:44:31.156+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:31.156+00007020: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f0014000c30 classname=virStoragePool
>2013-03-26 18:44:31.156+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:31.156+00007020: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f0024107070
>2013-03-26 18:44:31.156+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:31.156+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:31.156+00007020: debug : virStoragePoolDelete:12543 : pool=0x7f0014000c30, flags=0
>2013-03-26 18:44:31.156+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:31.156+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:31.156+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:31.156+00007020: debug : virCommandRunAsync:2209 : About to run /sbin/vgremove -f vg001
>2013-03-26 18:44:31.156+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:31.156+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:31.156+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:31.156+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=1 d=0
>2013-03-26 18:44:31.156+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:31.157+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323476155
>2013-03-26 18:44:31.157+00007020: debug : virFileClose:72 : Closed fd 24
>2013-03-26 18:44:31.157+00007020: debug : virFileClose:72 : Closed fd 26
>2013-03-26 18:44:31.157+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323476155 due in 4998 ms
>2013-03-26 18:44:31.157+00007020: debug : virFileClose:72 : Closed fd 28
>2013-03-26 18:44:31.157+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=4998
>2013-03-26 18:44:31.157+00007020: debug : virCommandRunAsync:2227 : Command result 0, with PID 17789
>2013-03-26 18:44:31.197+00007020: debug : virCommandRun:2025 : Result status 0, stdout: '  Volume group "vg001" successfully removed
>' stderr: ''
>2013-03-26 18:44:31.197+00007020: debug : virFileClose:72 : Closed fd 25
>2013-03-26 18:44:31.197+00007020: debug : virFileClose:72 : Closed fd 27
>
>
>     ====== end of log =====
>Caught abort signal dumping internal log buffer:
>
>
>    ====== start of log =====
>
>rProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=6 proc=89
>2013-03-26 18:44:23.452+00007026: debug : remoteDispatchStoragePoolGetAutostartHelper:11799 : server=0x13fecb0 client=0x1405060 msg=0x1406bc0 rerr=0x7f002dcbdb80 args=0x7f001800a900 ret=0x7f0018008ee0
>2013-03-26 18:44:23.452+00007026: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f001800a610 classname=virStoragePool
>2013-03-26 18:44:23.452+00007026: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f0024107070
>2013-03-26 18:44:23.452+00007026: debug : virStoragePoolGetAutostart:12879 : pool=0x7f001800a610, autostart=0x7f002dcbdadc
>2013-03-26 18:44:23.452+00007026: debug : virStoragePoolFree:12587 : pool=0x7f001800a610
>2013-03-26 18:44:23.452+00007026: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f001800a610
>2013-03-26 18:44:23.452+00007026: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f001800a610
>2013-03-26 18:44:23.452+00007026: debug : virStoragePoolDispose:443 : release pool 0x7f001800a610 vg001 57cae153-f029-e00c-5778-fed28a84fcda
>2013-03-26 18:44:23.452+00007026: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f0024107070
>2013-03-26 18:44:23.452+00007026: debug : virNetMessageEncodePayload:358 : Encode length as 32
>2013-03-26 18:44:23.452+00007026: debug : virNetServerClientSendMessageLocked:1119 : msg=0x1406bc0 proc=89 len=32 offset=0
>2013-03-26 18:44:23.452+00007026: debug : virNetServerClientSendMessageLocked:1127 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1405060 len=32 prog=536903814 vers=1 proc=89 type=1 status=0 serial=6
>2013-03-26 18:44:23.452+00007026: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1405140 tx=0x1406bc0
>2013-03-26 18:44:23.452+00007026: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2013-03-26 18:44:23.452+00007026: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=3
>2013-03-26 18:44:23.452+00007026: debug : virEventPollInterruptLocked:701 : Interrupting
>2013-03-26 18:44:23.452+00007026: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1409fe0
>2013-03-26 18:44:23.452+00007026: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1405060
>2013-03-26 18:44:23.452+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:23.452+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.452+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.452+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:23.452+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.452+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.452+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:23.452+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:23.452+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:23.452+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:23.452+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:23.452+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:23.452+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:23.452+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=5 d=0
>2013-03-26 18:44:23.452+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:23.452+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323468452
>2013-03-26 18:44:23.452+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323468452 due in 5000 ms
>2013-03-26 18:44:23.452+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-26 18:44:23.452+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:23.452+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2
>2013-03-26 18:44:23.452+00007017: debug : virNetMessageFree:73 : msg=0x1406bc0 nfds=0 cb=(nil)
>2013-03-26 18:44:23.452+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1405140 tx=(nil)
>2013-03-26 18:44:23.452+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.453+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323468452
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323468452 due in 4999 ms
>2013-03-26 18:44:23.453+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=4999
>2013-03-26 18:44:23.453+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1
>2013-03-26 18:44:23.453+00007017: debug : virNetMessageDecodeLength:152 : Got length, now need 56 total (52 more)
>2013-03-26 18:44:23.453+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1405140 tx=(nil)
>2013-03-26 18:44:23.453+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:23.453+00007017: debug : virNetServerClientDispatchRead:875 : RPC_SERVER_CLIENT_MSG_RX: client=0x1405060 len=56 prog=536903814 vers=1 proc=87 type=0 status=0 serial=7
>2013-03-26 18:44:23.453+00007017: debug : virKeepAliveCheckMessage:384 : ka=0x14054e0, client=0x1405060, msg=0x1405140
>2013-03-26 18:44:23.453+00007017: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=10 frequency=5000
>2013-03-26 18:44:23.453+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:23.453+00007017: debug : virObjectRef:168 : OBJECT_REF: obj=0x1405060
>2013-03-26 18:44:23.453+00007017: debug : virNetServerDispatchNewMessage:218 : server=0x13fecb0 client=0x1405060 message=0x1405140
>2013-03-26 18:44:23.453+00007017: debug : virObjectRef:168 : OBJECT_REF: obj=0x1409fe0
>2013-03-26 18:44:23.453+00007017: debug : virNetMessageNew:45 : msg=0x1405820 tracked=1
>2013-03-26 18:44:23.453+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1405820 tx=(nil)
>2013-03-26 18:44:23.453+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:23.453+00007023: debug : virNetServerHandleJob:189 : server=0x13fecb0 client=0x1405060 message=0x1405140 prog=0x1409fe0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:23.453+00007023: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=7 proc=87
>2013-03-26 18:44:23.453+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.453+00007023: debug : remoteDispatchStoragePoolGetInfoHelper:11855 : server=0x13fecb0 client=0x1405060 msg=0x1405140 rerr=0x7f002fac0b80 args=0x7f00241436e0 ret=0x7f0024143740
>2013-03-26 18:44:23.453+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.453+00007023: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f0024143700 classname=virStoragePool
>2013-03-26 18:44:23.453+00007023: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f0024107070
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:23.453+00007023: debug : virStoragePoolGetInfo:12789 : pool=0x7f0024143700, info=0x7f002fac0ac0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:23.453+00007023: debug : virStoragePoolFree:12587 : pool=0x7f0024143700
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:23.453+00007023: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f0024143700
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:23.453+00007023: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f0024143700
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:23.453+00007023: debug : virStoragePoolDispose:443 : release pool 0x7f0024143700 vg001 57cae153-f029-e00c-5778-fed28a84fcda
>2013-03-26 18:44:23.453+00007023: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f0024107070
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=1 d=0
>2013-03-26 18:44:23.453+00007023: debug : virNetMessageEncodePayload:358 : Encode length as 56
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:23.453+00007023: debug : virNetServerClientSendMessageLocked:1119 : msg=0x1405140 proc=87 len=56 offset=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323468453
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323468453 due in 5000 ms
>2013-03-26 18:44:23.453+00007023: debug : virNetServerClientSendMessageLocked:1127 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1405060 len=56 prog=536903814 vers=1 proc=87 type=1 status=0 serial=7
>2013-03-26 18:44:23.453+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-26 18:44:23.453+00007023: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1405820 tx=0x1405140
>2013-03-26 18:44:23.453+00007023: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2013-03-26 18:44:23.453+00007023: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=3
>2013-03-26 18:44:23.453+00007023: debug : virEventPollInterruptLocked:701 : Interrupting
>2013-03-26 18:44:23.453+00007023: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1409fe0
>2013-03-26 18:44:23.453+00007023: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1405060
>2013-03-26 18:44:23.453+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.453+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=5 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323468453
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323468453 due in 5000 ms
>2013-03-26 18:44:23.453+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-26 18:44:23.453+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2
>2013-03-26 18:44:23.453+00007017: debug : virNetMessageFree:73 : msg=0x1405140 nfds=0 cb=(nil)
>2013-03-26 18:44:23.453+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1405820 tx=(nil)
>2013-03-26 18:44:23.453+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.453+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=1 d=0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323468453
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323468453 due in 5000 ms
>2013-03-26 18:44:23.453+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-26 18:44:23.453+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:23.453+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1
>2013-03-26 18:44:23.453+00007017: debug : virNetMessageDecodeLength:152 : Got length, now need 56 total (52 more)
>2013-03-26 18:44:23.453+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1405820 tx=(nil)
>2013-03-26 18:44:23.453+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:23.453+00007017: debug : virNetServerClientDispatchRead:875 : RPC_SERVER_CLIENT_MSG_RX: client=0x1405060 len=56 prog=536903814 vers=1 proc=154 type=0 status=0 serial=8
>2013-03-26 18:44:23.453+00007017: debug : virKeepAliveCheckMessage:384 : ka=0x14054e0, client=0x1405060, msg=0x1405820
>2013-03-26 18:44:23.453+00007017: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=10 frequency=5000
>2013-03-26 18:44:23.453+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:23.453+00007017: debug : virObjectRef:168 : OBJECT_REF: obj=0x1405060
>2013-03-26 18:44:23.453+00007017: debug : virNetServerDispatchNewMessage:218 : server=0x13fecb0 client=0x1405060 message=0x1405820
>2013-03-26 18:44:23.453+00007017: debug : virObjectRef:168 : OBJECT_REF: obj=0x1409fe0
>2013-03-26 18:44:23.453+00007017: debug : virNetMessageNew:45 : msg=0x1407290 tracked=1
>2013-03-26 18:44:23.453+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1407290 tx=(nil)
>2013-03-26 18:44:23.453+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:23.453+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.453+00007024: debug : virNetServerHandleJob:189 : server=0x13fecb0 client=0x1405060 message=0x1405820 prog=0x1409fe0
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.453+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:23.453+00007024: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=8 proc=154
>2013-03-26 18:44:23.453+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:23.454+00007024: debug : remoteDispatchStoragePoolIsActiveHelper:11970 : server=0x13fecb0 client=0x1405060 msg=0x1405820 rerr=0x7f002f0bfb80 args=0x7f00040008c0 ret=0x7f0004000c50
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:23.454+00007024: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f0004000c10 classname=virStoragePool
>2013-03-26 18:44:23.454+00007024: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f0024107070
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:23.454+00007024: debug : virStoragePoolIsActive:16336 : pool=0x7f0004000c10
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:23.454+00007024: debug : virStoragePoolFree:12587 : pool=0x7f0004000c10
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:23.454+00007024: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f0004000c10
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=1 d=0
>2013-03-26 18:44:23.454+00007024: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f0004000c10
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323468453
>2013-03-26 18:44:23.454+00007024: debug : virStoragePoolDispose:443 : release pool 0x7f0004000c10 vg001 57cae153-f029-e00c-5778-fed28a84fcda
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323468453 due in 4999 ms
>2013-03-26 18:44:23.454+00007024: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f0024107070
>2013-03-26 18:44:23.454+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=4999
>2013-03-26 18:44:23.454+00007024: debug : virNetMessageEncodePayload:358 : Encode length as 32
>2013-03-26 18:44:23.454+00007024: debug : virNetServerClientSendMessageLocked:1119 : msg=0x1405820 proc=154 len=32 offset=0
>2013-03-26 18:44:23.454+00007024: debug : virNetServerClientSendMessageLocked:1127 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1405060 len=32 prog=536903814 vers=1 proc=154 type=1 status=0 serial=8
>2013-03-26 18:44:23.454+00007024: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1407290 tx=0x1405820
>2013-03-26 18:44:23.454+00007024: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2013-03-26 18:44:23.454+00007024: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=3
>2013-03-26 18:44:23.454+00007024: debug : virEventPollInterruptLocked:701 : Interrupting
>2013-03-26 18:44:23.454+00007024: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1409fe0
>2013-03-26 18:44:23.454+00007024: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1405060
>2013-03-26 18:44:23.454+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.454+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=5 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323468453
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323468453 due in 4999 ms
>2013-03-26 18:44:23.454+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=4999
>2013-03-26 18:44:23.454+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:23.454+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2
>2013-03-26 18:44:23.454+00007017: debug : virNetMessageFree:73 : msg=0x1405820 nfds=0 cb=(nil)
>2013-03-26 18:44:23.454+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1407290 tx=(nil)
>2013-03-26 18:44:23.454+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:23.454+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:23.454+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.454+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=1 d=0
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323468453
>2013-03-26 18:44:23.454+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323468453 due in 4999 ms
>2013-03-26 18:44:23.454+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=4999
>2013-03-26 18:44:28.458+00007017: debug : virEventPollRunOnce:625 : Poll got 0 event(s)
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchTimeouts:433 : EVENT_POLL_DISPATCH_TIMEOUT: timer=10
>2013-03-26 18:44:28.458+00007017: debug : virKeepAliveTimerInternal:148 : RPC_KEEPALIVE_TIMEOUT: ka=0x14054e0 client=0x1405060 countToDeath=5 idle=5
>2013-03-26 18:44:28.458+00007017: debug : virNetMessageNew:45 : msg=0x1407300 tracked=0
>2013-03-26 18:44:28.458+00007017: debug : virNetMessageEncodePayloadEmpty:448 : Encode length as 28
>2013-03-26 18:44:28.458+00007017: debug : virKeepAliveMessage:116 : Sending keepalive request to client 0x1405060
>2013-03-26 18:44:28.458+00007017: debug : virKeepAliveMessage:119 : RPC_KEEPALIVE_SEND: ka=0x14054e0 client=0x1405060 prog=1801807216 vers=1 proc=1
>2013-03-26 18:44:28.458+00007017: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=10 frequency=5000
>2013-03-26 18:44:28.458+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:28.458+00007017: debug : virObjectRef:168 : OBJECT_REF: obj=0x14054e0
>2013-03-26 18:44:28.458+00007017: debug : virNetServerClientSendMessageLocked:1119 : msg=0x1407300 proc=1 len=28 offset=0
>2013-03-26 18:44:28.458+00007017: debug : virNetServerClientSendMessageLocked:1127 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1405060 len=28 prog=1801807216 vers=1 proc=1 type=2 status=0 serial=0
>2013-03-26 18:44:28.458+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1407290 tx=0x1407300
>2013-03-26 18:44:28.458+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2013-03-26 18:44:28.458+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=3
>2013-03-26 18:44:28.458+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:28.458+00007017: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x14054e0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:28.458+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=5 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323473458
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323473458 due in 5000 ms
>2013-03-26 18:44:28.458+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-26 18:44:28.458+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:28.458+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2
>2013-03-26 18:44:28.458+00007017: debug : virNetMessageFree:73 : msg=0x1407300 nfds=0 cb=(nil)
>2013-03-26 18:44:28.458+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1407290 tx=(nil)
>2013-03-26 18:44:28.458+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:28.458+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:28.458+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:28.458+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=1 d=0
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323473458
>2013-03-26 18:44:28.458+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323473458 due in 5000 ms
>2013-03-26 18:44:28.458+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-26 18:44:28.459+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:28.459+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:28.459+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:28.459+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:28.459+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:28.459+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:28.459+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:28.459+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:28.459+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:28.459+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:28.459+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:28.459+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1
>2013-03-26 18:44:28.459+00007017: debug : virNetMessageDecodeLength:152 : Got length, now need 28 total (24 more)
>2013-03-26 18:44:28.459+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1407290 tx=(nil)
>2013-03-26 18:44:28.459+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:28.459+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:28.459+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:28.459+00007017: debug : virNetServerClientDispatchRead:875 : RPC_SERVER_CLIENT_MSG_RX: client=0x1405060 len=28 prog=1801807216 vers=1 proc=2 type=2 status=0 serial=0
>2013-03-26 18:44:28.459+00007017: debug : virKeepAliveCheckMessage:384 : ka=0x14054e0, client=0x1405060, msg=0x1407290
>2013-03-26 18:44:28.459+00007017: debug : virKeepAliveCheckMessage:401 : RPC_KEEPALIVE_RECEIVED: ka=0x14054e0 client=0x1405060 prog=1801807216 vers=1 proc=2
>2013-03-26 18:44:28.459+00007017: debug : virKeepAliveCheckMessage:410 : Got keepalive response from client 0x1405060
>2013-03-26 18:44:28.459+00007017: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=10 frequency=5000
>2013-03-26 18:44:28.459+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:28.459+00007017: debug : virNetMessageFree:73 : msg=0x1407290 nfds=0 cb=(nil)
>2013-03-26 18:44:28.459+00007017: debug : virNetMessageNew:45 : msg=0x1407290 tracked=1
>2013-03-26 18:44:28.459+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1407290 tx=(nil)
>2013-03-26 18:44:28.459+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:28.459+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:28.459+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:28.459+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:28.459+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:28.459+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:28.459+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:28.459+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:28.459+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:28.459+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:28.459+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:28.459+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:28.459+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:28.459+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:28.459+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:28.459+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=1 d=0
>2013-03-26 18:44:28.459+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:28.459+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323473459
>2013-03-26 18:44:28.459+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323473459 due in 5000 ms
>2013-03-26 18:44:28.459+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-26 18:44:31.155+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1
>2013-03-26 18:44:31.155+00007017: debug : virNetMessageDecodeLength:152 : Got length, now need 40 total (36 more)
>2013-03-26 18:44:31.155+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1407290 tx=(nil)
>2013-03-26 18:44:31.155+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:31.155+00007017: debug : virNetServerClientDispatchRead:875 : RPC_SERVER_CLIENT_MSG_RX: client=0x1405060 len=40 prog=536903814 vers=1 proc=84 type=0 status=0 serial=9
>2013-03-26 18:44:31.155+00007017: debug : virKeepAliveCheckMessage:384 : ka=0x14054e0, client=0x1405060, msg=0x1407290
>2013-03-26 18:44:31.155+00007017: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=10 frequency=5000
>2013-03-26 18:44:31.155+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:31.155+00007017: debug : virObjectRef:168 : OBJECT_REF: obj=0x1405060
>2013-03-26 18:44:31.155+00007017: debug : virNetServerDispatchNewMessage:218 : server=0x13fecb0 client=0x1405060 message=0x1407290
>2013-03-26 18:44:31.155+00007017: debug : virObjectRef:168 : OBJECT_REF: obj=0x1409fe0
>2013-03-26 18:44:31.155+00007017: debug : virNetMessageNew:45 : msg=0x1406b50 tracked=1
>2013-03-26 18:44:31.155+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1406b50 tx=(nil)
>2013-03-26 18:44:31.155+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:31.155+00007022: debug : virNetServerHandleJob:189 : server=0x13fecb0 client=0x1405060 message=0x1407290 prog=0x1409fe0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:31.155+00007022: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=9 proc=84
>2013-03-26 18:44:31.155+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:31.155+00007022: debug : remoteDispatchStoragePoolLookupByNameHelper:12174 : server=0x13fecb0 client=0x1405060 msg=0x1407290 rerr=0x7f00304c1b80 args=0x7f00200f0fe0 ret=0x7f00200f1ef0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:31.155+00007022: debug : virStoragePoolLookupByName:12107 : conn=0x7f0024107070, name=vg001
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:31.155+00007022: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f00200f11a0 classname=virStoragePool
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:31.155+00007022: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f0024107070
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:31.155+00007022: debug : virStoragePoolFree:12587 : pool=0x7f00200f11a0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:31.155+00007022: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f00200f11a0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:31.155+00007022: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x7f00200f11a0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=1 d=0
>2013-03-26 18:44:31.155+00007022: debug : virStoragePoolDispose:443 : release pool 0x7f00200f11a0 vg001 57cae153-f029-e00c-5778-fed28a84fcda
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:31.155+00007022: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x7f0024107070
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323476155
>2013-03-26 18:44:31.155+00007022: debug : virNetMessageEncodePayload:358 : Encode length as 56
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323476155 due in 5000 ms
>2013-03-26 18:44:31.155+00007022: debug : virNetServerClientSendMessageLocked:1119 : msg=0x1407290 proc=84 len=56 offset=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-26 18:44:31.155+00007022: debug : virNetServerClientSendMessageLocked:1127 : RPC_SERVER_CLIENT_MSG_TX_QUEUE: client=0x1405060 len=56 prog=536903814 vers=1 proc=84 type=1 status=0 serial=9
>2013-03-26 18:44:31.155+00007022: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1406b50 tx=0x1407290
>2013-03-26 18:44:31.155+00007022: debug : virNetServerClientCalculateHandleMode:183 : mode=3
>2013-03-26 18:44:31.155+00007022: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=3
>2013-03-26 18:44:31.155+00007022: debug : virEventPollInterruptLocked:701 : Interrupting
>2013-03-26 18:44:31.155+00007022: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1409fe0
>2013-03-26 18:44:31.155+00007022: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x1405060
>2013-03-26 18:44:31.155+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:31.155+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=5 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323476155
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323476155 due in 5000 ms
>2013-03-26 18:44:31.155+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-26 18:44:31.155+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=2
>2013-03-26 18:44:31.155+00007017: debug : virNetMessageFree:73 : msg=0x1407290 nfds=0 cb=(nil)
>2013-03-26 18:44:31.155+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1406b50 tx=(nil)
>2013-03-26 18:44:31.155+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:31.155+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=1 d=0
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323476155
>2013-03-26 18:44:31.155+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323476155 due in 5000 ms
>2013-03-26 18:44:31.155+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=5000
>2013-03-26 18:44:31.155+00007017: debug : virEventPollRunOnce:625 : Poll got 1 event(s)
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchTimeouts:410 : Dispatch 2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:455 : Dispatch 8
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=0 w=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=1 w=2
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=2 w=3
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=3 w=4
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=4 w=5
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=5 w=6
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=6 w=7
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:469 : i=7 w=12
>2013-03-26 18:44:31.155+00007017: debug : virEventPollDispatchHandles:483 : EVENT_POLL_DISPATCH_HANDLE: watch=12 events=1
>2013-03-26 18:44:31.155+00007017: debug : virNetMessageDecodeLength:152 : Got length, now need 60 total (56 more)
>2013-03-26 18:44:31.155+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1406b50 tx=(nil)
>2013-03-26 18:44:31.155+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:31.155+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:31.155+00007017: debug : virNetServerClientDispatchRead:875 : RPC_SERVER_CLIENT_MSG_RX: client=0x1405060 len=60 prog=536903814 vers=1 proc=81 type=0 status=0 serial=10
>2013-03-26 18:44:31.155+00007017: debug : virKeepAliveCheckMessage:384 : ka=0x14054e0, client=0x1405060, msg=0x1406b50
>2013-03-26 18:44:31.155+00007017: debug : virEventPollUpdateTimeout:254 : EVENT_POLL_UPDATE_TIMEOUT: timer=10 frequency=5000
>2013-03-26 18:44:31.155+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:31.155+00007017: debug : virObjectRef:168 : OBJECT_REF: obj=0x1405060
>2013-03-26 18:44:31.156+00007017: debug : virNetServerDispatchNewMessage:218 : server=0x13fecb0 client=0x1405060 message=0x1406b50
>2013-03-26 18:44:31.156+00007017: debug : virObjectRef:168 : OBJECT_REF: obj=0x1409fe0
>2013-03-26 18:44:31.156+00007017: debug : virNetMessageNew:45 : msg=0x1405900 tracked=1
>2013-03-26 18:44:31.156+00007017: debug : virNetServerClientCalculateHandleMode:152 : tls=(nil) hs=-1, rx=0x1405900 tx=(nil)
>2013-03-26 18:44:31.156+00007017: debug : virNetServerClientCalculateHandleMode:183 : mode=1
>2013-03-26 18:44:31.156+00007020: debug : virNetServerHandleJob:189 : server=0x13fecb0 client=0x1405060 message=0x1406b50 prog=0x1409fe0
>2013-03-26 18:44:31.156+00007017: debug : virEventPollUpdateHandle:146 : EVENT_POLL_UPDATE_HANDLE: watch=12 events=1
>2013-03-26 18:44:31.156+00007020: debug : virNetServerProgramDispatch:284 : prog=536903814 ver=1 type=0 status=0 serial=10 proc=81
>2013-03-26 18:44:31.156+00007017: debug : virEventPollInterruptLocked:697 : Skip interrupt, 1 1094785120
>2013-03-26 18:44:31.156+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:31.156+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:31.156+00007020: debug : remoteDispatchStoragePoolDeleteHelper:11694 : server=0x13fecb0 client=0x1405060 msg=0x1406b50 rerr=0x7f00318c3b80 args=0x7f0014000c70 ret=0x7f0014000b20
>2013-03-26 18:44:31.156+00007017: debug : virEventRunDefaultImpl:244 : running default event implementation
>2013-03-26 18:44:31.156+00007020: debug : virObjectNew:110 : OBJECT_NEW: obj=0x7f0014000c30 classname=virStoragePool
>2013-03-26 18:44:31.156+00007017: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
>2013-03-26 18:44:31.156+00007020: debug : virObjectRef:168 : OBJECT_REF: obj=0x7f0024107070
>2013-03-26 18:44:31.156+00007017: debug : virEventPollCleanupHandles:549 : Cleanup 8
>2013-03-26 18:44:31.156+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=7 e=1 d=0
>2013-03-26 18:44:31.156+00007020: debug : virStoragePoolDelete:12543 : pool=0x7f0014000c30, flags=0
>2013-03-26 18:44:31.156+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=9 e=1 d=0
>2013-03-26 18:44:31.156+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=12 e=1 d=0
>2013-03-26 18:44:31.156+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=13 e=1 d=0
>2013-03-26 18:44:31.156+00007020: debug : virCommandRunAsync:2209 : About to run /sbin/vgremove -f vg001
>2013-03-26 18:44:31.156+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=6 e=1 d=0
>2013-03-26 18:44:31.156+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
>2013-03-26 18:44:31.156+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=17 e=1 d=0
>2013-03-26 18:44:31.156+00007017: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=12, f=19 e=1 d=0
>2013-03-26 18:44:31.156+00007017: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
>2013-03-26 18:44:31.157+00007017: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1364323476155
>2013-03-26 18:44:31.157+00007020: debug : virFileClose:72 : Closed fd 24
>2013-03-26 18:44:31.157+00007020: debug : virFileClose:72 : Closed fd 26
>2013-03-26 18:44:31.157+00007017: debug : virEventPollCalculateTimeout:346 : Timeout at 1364323476155 due in 4998 ms
>2013-03-26 18:44:31.157+00007020: debug : virFileClose:72 : Closed fd 28
>2013-03-26 18:44:31.157+00007017: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=8 timeout=4998
>2013-03-26 18:44:31.157+00007020: debug : virCommandRunAsync:2227 : Command result 0, with PID 17789
>2013-03-26 18:44:31.197+00007020: debug : virCommandRun:2025 : Result status 0, stdout: '  Volume group "vg001" successfully removed
>' stderr: ''
>2013-03-26 18:44:31.197+00007020: debug : virFileClose:72 : Closed fd 25
>2013-03-26 18:44:31.197+00007020: debug : virFileClose:72 : Closed fd 27
>
>
>     ====== end of log =====

Actions: View

Attachments on bug 921387: 709876 | 915683