Login
[x]
Log in using an account from:
Fedora Account System
Red Hat Associate
Red Hat Customer
Or login using a Red Hat Bugzilla account
Forgot Password
Login:
Hide Forgot
Create an Account
Red Hat Bugzilla – Attachment 915683 Details for
Bug 921387
libvirtd crashes when deleting logical storage pool
[?]
New
Simple Search
Advanced Search
My Links
Browse
Requests
Reports
Current State
Search
Tabular reports
Graphical reports
Duplicates
Other Reports
User Changes
Plotly Reports
Bug Status
Bug Severity
Non-Defaults
|
Product Dashboard
Help
Page Help!
Bug Writing Guidelines
What's new
Browser Support Policy
5.0.4.rh83 Release notes
FAQ
Guides index
User guide
Web Services
Contact
Legal
This site requires JavaScript to be enabled to function correctly, please enable it.
Comment
comment_5635717.txt (text/plain), 129.27 KB, created by
Brian Kruger
on 2013-03-26 18:54:00 UTC
(
hide
)
Description:
Comment
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 =====
You cannot view the attachment while viewing its details because your browser does not support IFRAMEs.
View the attachment on a separate page
.
View Attachment As Raw
Actions:
View
Attachments on
bug 921387
:
709876
| 915683