Attachment 854872 Details for Bug 1057535 – LIBVIRT_DEBUG=1 virsh capabilities

LIBVIRT_DEBUG=1 virsh capabilities

debug.txt (text/plain), 38.96 KB, created by Richard W.M. Jones on 2014-01-24 10:00:44 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Richard W.M. Jones

Created: 2014-01-24 10:00:44 UTC

Size: 38.96 KB

patch

obsolete

>2014-01-24 09:59:02.609+0000: 25699: info : libvirt version: 1.1.3.2, package: 1.fc20 (Fedora Project, 2013-12-14-21:11:12, buildvm-04.phx2.fedoraproject.org)
>2014-01-24 09:59:02.609+0000: 25699: debug : virGlobalInit:446 : register drivers
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterDriver:778 : driver=0x7f717a716b00 name=Test
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterDriver:790 : registering Test as driver 0
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterNetworkDriver:625 : registering Test as network driver 0
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterInterfaceDriver:652 : registering Test as interface driver 0
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterStorageDriver:679 : registering Test as storage driver 0
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterNodeDeviceDriver:706 : registering Test as device driver 0
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterSecretDriver:733 : registering Test as secret driver 0
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterNWFilterDriver:760 : registering Test as network filter driver 0
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterDriver:778 : driver=0x7f717a718640 name=OPENVZ
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterDriver:790 : registering OPENVZ as driver 1
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterDriver:778 : driver=0x7f717a718ca0 name=VMWARE
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterDriver:790 : registering VMWARE as driver 2
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterDriver:778 : driver=0x7f717a718000 name=PHYP
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterDriver:790 : registering PHYP as driver 3
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterStorageDriver:679 : registering PHYP as storage driver 1
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterInterfaceDriver:652 : registering PHYP as interface driver 1
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterDriver:778 : driver=0x7f717a7192c0 name=ESX
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterDriver:790 : registering ESX as driver 4
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterInterfaceDriver:652 : registering ESX as interface driver 2
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterNetworkDriver:625 : registering ESX as network driver 1
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterStorageDriver:679 : registering ESX as storage driver 2
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterNodeDeviceDriver:706 : registering ESX as device driver 1
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterSecretDriver:733 : registering ESX as secret driver 1
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterNWFilterDriver:760 : registering ESX as network filter driver 1
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterDriver:778 : driver=0x7f717a719fc0 name=Hyper-V
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterDriver:790 : registering Hyper-V as driver 5
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterInterfaceDriver:652 : registering Hyper-V as interface driver 3
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterNetworkDriver:625 : registering Hyper-V as network driver 2
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterStorageDriver:679 : registering Hyper-V as storage driver 3
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterNodeDeviceDriver:706 : registering Hyper-V as device driver 2
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterSecretDriver:733 : registering Hyper-V as secret driver 2
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterNWFilterDriver:760 : registering Hyper-V as network filter driver 2
>2014-01-24 09:59:02.609+0000: 25699: debug : parallelsRegister:2423 : Can't find prlctl command in the PATH env
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterDriver:778 : driver=0x7f717a717580 name=remote
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterDriver:790 : registering remote as driver 6
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterNetworkDriver:625 : registering remote as network driver 3
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterInterfaceDriver:652 : registering remote as interface driver 4
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterStorageDriver:679 : registering remote as storage driver 4
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterNodeDeviceDriver:706 : registering remote as device driver 3
>2014-01-24 09:59:02.609+0000: 25699: debug : virRegisterSecretDriver:733 : registering remote as secret driver 3
>2014-01-24 09:59:02.610+0000: 25699: debug : virRegisterNWFilterDriver:760 : registering remote as network filter driver 3
>2014-01-24 09:59:02.610+0000: 25699: debug : virEventRegisterDefaultImpl:231 : registering default event implementation
>2014-01-24 09:59:02.610+0000: 25699: debug : virEventPollAddHandle:111 : Used 0 handle slots, adding at least 10 more
>2014-01-24 09:59:02.610+0000: 25699: debug : virEventPollInterruptLocked:713 : Skip interrupt, 0 0
>2014-01-24 09:59:02.610+0000: 25699: debug : virEventPollAddHandle:136 : EVENT_POLL_ADD_HANDLE: watch=1 fd=4 events=1 cb=0x7f717a272320 opaque=(nil) ff=(nil)
>2014-01-24 09:59:02.610+0000: 25699: debug : virEventRegisterImpl:204 : addHandle=0x7f717a272a50 updateHandle=0x7f717a272da0 removeHandle=0x7f717a272380 addTimeout=0x7f717a2724f0 updateTimeout=0x7f717a272700 removeTimeout=0x7f717a2728d0
>2014-01-24 09:59:02.610+0000: 25699: debug : virConnectOpenAuth:1479 : name=(null), auth=0x7f717a716640, flags=0
>2014-01-24 09:59:02.610+0000: 25699: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f717bcb83a0 classname=virConnect
>2014-01-24 09:59:02.610+0000: 25699: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f717bcb8540 classname=virConnectCloseCallbackData
>2014-01-24 09:59:02.610+0000: 25700: debug : virEventRunDefaultImpl:271 : running default event implementation
>2014-01-24 09:59:02.610+0000: 25700: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-01-24 09:59:02.610+0000: 25700: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-01-24 09:59:02.610+0000: 25700: debug : virEventPollCleanupHandles:565 : Cleanup 1
>2014-01-24 09:59:02.610+0000: 25700: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
>2014-01-24 09:59:02.610+0000: 25700: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
>2014-01-24 09:59:02.610+0000: 25700: debug : virEventPollCalculateTimeout:364 : No timeout is pending
>2014-01-24 09:59:02.610+0000: 25700: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=1 timeout=-1
>2014-01-24 09:59:02.610+0000: 25699: debug : do_open:1195 : no name, allowing driver auto-select
>2014-01-24 09:59:02.610+0000: 25699: debug : do_open:1237 : trying driver 0 (Test) ...
>2014-01-24 09:59:02.610+0000: 25699: debug : do_open:1244 : driver 0 Test returned DECLINED
>2014-01-24 09:59:02.610+0000: 25699: debug : do_open:1237 : trying driver 1 (OPENVZ) ...
>2014-01-24 09:59:02.610+0000: 25699: debug : do_open:1244 : driver 1 OPENVZ returned DECLINED
>2014-01-24 09:59:02.610+0000: 25699: debug : do_open:1237 : trying driver 2 (VMWARE) ...
>2014-01-24 09:59:02.610+0000: 25699: debug : do_open:1244 : driver 2 VMWARE returned DECLINED
>2014-01-24 09:59:02.610+0000: 25699: debug : do_open:1237 : trying driver 3 (PHYP) ...
>2014-01-24 09:59:02.610+0000: 25699: debug : do_open:1244 : driver 3 PHYP returned DECLINED
>2014-01-24 09:59:02.610+0000: 25699: debug : do_open:1237 : trying driver 4 (ESX) ...
>2014-01-24 09:59:02.610+0000: 25699: debug : do_open:1244 : driver 4 ESX returned DECLINED
>2014-01-24 09:59:02.610+0000: 25699: debug : do_open:1237 : trying driver 5 (Hyper-V) ...
>2014-01-24 09:59:02.610+0000: 25699: debug : do_open:1244 : driver 5 Hyper-V returned DECLINED
>2014-01-24 09:59:02.610+0000: 25699: debug : do_open:1237 : trying driver 6 (remote) ...
>2014-01-24 09:59:02.610+0000: 25699: debug : remoteConnectOpen:1014 : Auto-probe remote URI
>2014-01-24 09:59:02.610+0000: 25699: debug : remoteConnectOpen:1017 : Auto-spawn user daemon instance
>2014-01-24 09:59:02.610+0000: 25699: debug : doRemoteOpen:613 : proceeding with name = 
>2014-01-24 09:59:02.610+0000: 25699: debug : doRemoteOpen:622 : Connecting with transport 1
>2014-01-24 09:59:02.610+0000: 25699: debug : doRemoteOpen:713 : Proceeding with sockname /run/user/1000/libvirt/libvirt-sock
>2014-01-24 09:59:02.612+0000: 25699: debug : virNetSocketNew:155 : localAddr=0x7fff4a6927a0 remoteAddr=0x7fff4a692830 fd=6 errfd=-1 pid=0
>2014-01-24 09:59:02.612+0000: 25699: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f717bcb8620 classname=virNetSocket
>2014-01-24 09:59:02.612+0000: 25699: debug : virNetSocketNew:205 : RPC_SOCKET_NEW: sock=0x7f717bcb8620 fd=6 errfd=-1 pid=0 localAddr=127.0.0.1;0, remoteAddr=127.0.0.1;0
>2014-01-24 09:59:02.612+0000: 25699: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f717bcb9a70 classname=virNetClient
>2014-01-24 09:59:02.612+0000: 25699: debug : virNetClientNew:326 : RPC_CLIENT_NEW: client=0x7f717bcb9a70 sock=0x7f717bcb8620
>2014-01-24 09:59:02.612+0000: 25699: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f717bcb9a70
>2014-01-24 09:59:02.612+0000: 25699: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f717bcb8620
>2014-01-24 09:59:02.612+0000: 25699: debug : virEventPollInterruptLocked:717 : Interrupting
>2014-01-24 09:59:02.612+0000: 25699: debug : virEventPollAddHandle:136 : EVENT_POLL_ADD_HANDLE: watch=2 fd=6 events=1 cb=0x7f717a392190 opaque=0x7f717bcb8620 ff=0x7f717a392140
>2014-01-24 09:59:02.612+0000: 25699: debug : virKeepAliveNew:196 : client=0x7f717bcb9a70, interval=-1, count=0
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
>2014-01-24 09:59:02.612+0000: 25699: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f717bcb8800 classname=virKeepAlive
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
>2014-01-24 09:59:02.612+0000: 25699: debug : virKeepAliveNew:215 : RPC_KEEPALIVE_NEW: ka=0x7f717bcb8800 client=0x7f717bcb9a70
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollDispatchHandles:471 : Dispatch 1
>2014-01-24 09:59:02.612+0000: 25699: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f717bcb9a70
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollDispatchHandles:485 : i=0 w=1
>2014-01-24 09:59:02.612+0000: 25699: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f717bcb8540
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-01-24 09:59:02.612+0000: 25699: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f717bcb8900 classname=virNetClientProgram
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-01-24 09:59:02.612+0000: 25699: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f717bcb96d0 classname=virNetClientProgram
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-01-24 09:59:02.612+0000: 25699: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f717bcb8b60 classname=virNetClientProgram
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-01-24 09:59:02.612+0000: 25699: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f717bcb8900
>2014-01-24 09:59:02.612+0000: 25699: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f717bcb96d0
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventRunDefaultImpl:271 : running default event implementation
>2014-01-24 09:59:02.612+0000: 25699: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f717bcb8b60
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-01-24 09:59:02.612+0000: 25699: debug : doRemoteOpen:831 : Trying authentication
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-01-24 09:59:02.612+0000: 25699: debug : virNetMessageNew:44 : msg=0x7f717bcb9c10 tracked=0
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
>2014-01-24 09:59:02.612+0000: 25699: debug : virNetMessageEncodePayload:373 : Encode length as 28
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=1 d=0
>2014-01-24 09:59:02.612+0000: 25699: debug : virNetClientSendInternal:1952 : RPC_CLIENT_MSG_TX_QUEUE: client=0x7f717bcb9a70 len=28 prog=536903814 vers=1 proc=66 type=0 status=0 serial=0
>2014-01-24 09:59:02.612+0000: 25699: debug : virNetClientCallNew:1905 : New call 0x7f717bcb9770: msg=0x7f717bcb9c10, expectReply=1, nonBlock=0
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
>2014-01-24 09:59:02.612+0000: 25699: debug : virNetClientIO:1714 : Outgoing message prog=536903814 version=1 serial=0 proc=66 type=0 length=28 dispatch=(nil)
>2014-01-24 09:59:02.612+0000: 25699: debug : virNetClientIO:1773 : We have the buck head=0x7f717bcb9770 call=0x7f717bcb9770
>2014-01-24 09:59:02.612+0000: 25699: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollCalculateTimeout:364 : No timeout is pending
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=2 timeout=-1
>2014-01-24 09:59:02.612+0000: 25699: debug : virEventPollInterruptLocked:717 : Interrupting
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollDispatchHandles:471 : Dispatch 2
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollDispatchHandles:485 : i=0 w=1
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventRunDefaultImpl:271 : running default event implementation
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=0 d=0
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollCalculateTimeout:364 : No timeout is pending
>2014-01-24 09:59:02.612+0000: 25700: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=1 timeout=-1
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetMessageDecodeLength:149 : Got length, now need 36 total (32 more)
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetClientCallDispatch:1123 : RPC_CLIENT_MSG_RX: client=0x7f717bcb9a70 len=36 prog=536903814 vers=1 proc=66 type=1 status=0 serial=0
>2014-01-24 09:59:02.613+0000: 25699: debug : virKeepAliveCheckMessage:374 : ka=0x7f717bcb8800, client=0x7f717bcb9a70, msg=0x7f717bcb9ad8
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetMessageClear:55 : msg=0x7f717bcb9ad8 nfds=0
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetClientIOEventLoopPassTheBuck:1420 : Giving up the buck 0x7f717bcb9770
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetClientIOEventLoopPassTheBuck:1434 : No thread to pass the buck to
>2014-01-24 09:59:02.613+0000: 25699: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1
>2014-01-24 09:59:02.613+0000: 25699: debug : virEventPollInterruptLocked:717 : Interrupting
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetClientIO:1803 : All done with our call head=(nil) call=0x7f717bcb9770 rv=0
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetMessageFree:72 : msg=0x7f717bcb9c10 nfds=0 cb=(nil)
>2014-01-24 09:59:02.613+0000: 25700: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetMessageNew:44 : msg=0x7f717bcb9c10 tracked=0
>2014-01-24 09:59:02.613+0000: 25700: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetMessageEncodePayload:373 : Encode length as 32
>2014-01-24 09:59:02.613+0000: 25700: debug : virEventPollDispatchHandles:471 : Dispatch 1
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetClientSendInternal:1952 : RPC_CLIENT_MSG_TX_QUEUE: client=0x7f717bcb9a70 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=1
>2014-01-24 09:59:02.613+0000: 25700: debug : virEventPollDispatchHandles:485 : i=0 w=1
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetClientCallNew:1905 : New call 0x7f717bcb8890: msg=0x7f717bcb9c10, expectReply=1, nonBlock=0
>2014-01-24 09:59:02.613+0000: 25700: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetClientIO:1714 : Outgoing message prog=536903814 version=1 serial=1 proc=60 type=0 length=32 dispatch=(nil)
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetClientIO:1773 : We have the buck head=0x7f717bcb8890 call=0x7f717bcb8890
>2014-01-24 09:59:02.613+0000: 25700: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-01-24 09:59:02.613+0000: 25699: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0
>2014-01-24 09:59:02.613+0000: 25700: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-01-24 09:59:02.613+0000: 25700: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-01-24 09:59:02.613+0000: 25700: debug : virEventRunDefaultImpl:271 : running default event implementation
>2014-01-24 09:59:02.613+0000: 25699: debug : virEventPollInterruptLocked:713 : Skip interrupt, 0 140125097694976
>2014-01-24 09:59:02.613+0000: 25700: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-01-24 09:59:02.613+0000: 25700: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-01-24 09:59:02.613+0000: 25700: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-01-24 09:59:02.613+0000: 25700: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
>2014-01-24 09:59:02.613+0000: 25700: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=0 d=0
>2014-01-24 09:59:02.613+0000: 25700: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
>2014-01-24 09:59:02.613+0000: 25700: debug : virEventPollCalculateTimeout:364 : No timeout is pending
>2014-01-24 09:59:02.613+0000: 25700: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=1 timeout=-1
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetMessageDecodeLength:149 : Got length, now need 32 total (28 more)
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetClientCallDispatch:1123 : RPC_CLIENT_MSG_RX: client=0x7f717bcb9a70 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=1
>2014-01-24 09:59:02.613+0000: 25699: debug : virKeepAliveCheckMessage:374 : ka=0x7f717bcb8800, client=0x7f717bcb9a70, msg=0x7f717bcb9ad8
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetMessageClear:55 : msg=0x7f717bcb9ad8 nfds=0
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetClientIOEventLoopPassTheBuck:1420 : Giving up the buck 0x7f717bcb8890
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetClientIOEventLoopPassTheBuck:1434 : No thread to pass the buck to
>2014-01-24 09:59:02.613+0000: 25699: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1
>2014-01-24 09:59:02.613+0000: 25699: debug : virEventPollInterruptLocked:717 : Interrupting
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetClientIO:1803 : All done with our call head=(nil) call=0x7f717bcb8890 rv=0
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetMessageFree:72 : msg=0x7f717bcb9c10 nfds=0 cb=(nil)
>2014-01-24 09:59:02.613+0000: 25700: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
>2014-01-24 09:59:02.613+0000: 25699: debug : doRemoteOpen:857 : Trying to open URI 
>2014-01-24 09:59:02.613+0000: 25700: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetMessageNew:44 : msg=0x7f717bcb8890 tracked=0
>2014-01-24 09:59:02.613+0000: 25700: debug : virEventPollDispatchHandles:471 : Dispatch 1
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetMessageEncodePayload:373 : Encode length as 40
>2014-01-24 09:59:02.613+0000: 25700: debug : virEventPollDispatchHandles:485 : i=0 w=1
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetClientSendInternal:1952 : RPC_CLIENT_MSG_TX_QUEUE: client=0x7f717bcb9a70 len=40 prog=536903814 vers=1 proc=1 type=0 status=0 serial=2
>2014-01-24 09:59:02.613+0000: 25700: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetClientCallNew:1905 : New call 0x7f717bcb9880: msg=0x7f717bcb8890, expectReply=1, nonBlock=0
>2014-01-24 09:59:02.613+0000: 25700: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetClientIO:1714 : Outgoing message prog=536903814 version=1 serial=2 proc=1 type=0 length=40 dispatch=(nil)
>2014-01-24 09:59:02.613+0000: 25700: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-01-24 09:59:02.613+0000: 25699: debug : virNetClientIO:1773 : We have the buck head=0x7f717bcb9880 call=0x7f717bcb9880
>2014-01-24 09:59:02.613+0000: 25700: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-01-24 09:59:02.613+0000: 25699: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventRunDefaultImpl:271 : running default event implementation
>2014-01-24 09:59:02.614+0000: 25699: debug : virEventPollInterruptLocked:713 : Skip interrupt, 0 140125097694976
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=0 d=0
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventPollCalculateTimeout:364 : No timeout is pending
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=1 timeout=-1
>2014-01-24 09:59:02.614+0000: 25699: debug : virNetMessageDecodeLength:149 : Got length, now need 28 total (24 more)
>2014-01-24 09:59:02.614+0000: 25699: debug : virNetClientCallDispatch:1123 : RPC_CLIENT_MSG_RX: client=0x7f717bcb9a70 len=28 prog=536903814 vers=1 proc=1 type=1 status=0 serial=2
>2014-01-24 09:59:02.614+0000: 25699: debug : virKeepAliveCheckMessage:374 : ka=0x7f717bcb8800, client=0x7f717bcb9a70, msg=0x7f717bcb9ad8
>2014-01-24 09:59:02.614+0000: 25699: debug : virNetMessageClear:55 : msg=0x7f717bcb9ad8 nfds=0
>2014-01-24 09:59:02.614+0000: 25699: debug : virNetClientIOEventLoopPassTheBuck:1420 : Giving up the buck 0x7f717bcb9880
>2014-01-24 09:59:02.614+0000: 25699: debug : virNetClientIOEventLoopPassTheBuck:1434 : No thread to pass the buck to
>2014-01-24 09:59:02.614+0000: 25699: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1
>2014-01-24 09:59:02.614+0000: 25699: debug : virEventPollInterruptLocked:717 : Interrupting
>2014-01-24 09:59:02.614+0000: 25699: debug : virNetClientIO:1803 : All done with our call head=(nil) call=0x7f717bcb9880 rv=0
>2014-01-24 09:59:02.614+0000: 25699: debug : virNetMessageFree:72 : msg=0x7f717bcb8890 nfds=0 cb=(nil)
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
>2014-01-24 09:59:02.614+0000: 25699: debug : doRemoteOpen:868 : Trying to query remote URI
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
>2014-01-24 09:59:02.614+0000: 25699: debug : virNetMessageNew:44 : msg=0x7f717bcb9770 tracked=0
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventPollDispatchHandles:471 : Dispatch 1
>2014-01-24 09:59:02.614+0000: 25699: debug : virNetMessageEncodePayload:373 : Encode length as 28
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventPollDispatchHandles:485 : i=0 w=1
>2014-01-24 09:59:02.614+0000: 25699: debug : virNetClientSendInternal:1952 : RPC_CLIENT_MSG_TX_QUEUE: client=0x7f717bcb9a70 len=28 prog=536903814 vers=1 proc=110 type=0 status=0 serial=3
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-01-24 09:59:02.614+0000: 25699: debug : virNetClientCallNew:1905 : New call 0x7f717bcb9880: msg=0x7f717bcb9770, expectReply=1, nonBlock=0
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-01-24 09:59:02.614+0000: 25699: debug : virNetClientIO:1714 : Outgoing message prog=536903814 version=1 serial=3 proc=110 type=0 length=28 dispatch=(nil)
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-01-24 09:59:02.614+0000: 25699: debug : virNetClientIO:1773 : We have the buck head=0x7f717bcb9880 call=0x7f717bcb9880
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-01-24 09:59:02.614+0000: 25699: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventRunDefaultImpl:271 : running default event implementation
>2014-01-24 09:59:02.614+0000: 25699: debug : virEventPollInterruptLocked:713 : Skip interrupt, 0 140125097694976
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=0 d=0
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventPollCalculateTimeout:364 : No timeout is pending
>2014-01-24 09:59:02.614+0000: 25700: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=1 timeout=-1
>2014-01-24 09:59:02.615+0000: 25699: debug : virNetMessageDecodeLength:149 : Got length, now need 48 total (44 more)
>2014-01-24 09:59:02.615+0000: 25699: debug : virNetClientCallDispatch:1123 : RPC_CLIENT_MSG_RX: client=0x7f717bcb9a70 len=48 prog=536903814 vers=1 proc=110 type=1 status=0 serial=3
>2014-01-24 09:59:02.615+0000: 25699: debug : virKeepAliveCheckMessage:374 : ka=0x7f717bcb8800, client=0x7f717bcb9a70, msg=0x7f717bcb9ad8
>2014-01-24 09:59:02.615+0000: 25699: debug : virNetMessageClear:55 : msg=0x7f717bcb9ad8 nfds=0
>2014-01-24 09:59:02.615+0000: 25699: debug : virNetClientIOEventLoopPassTheBuck:1420 : Giving up the buck 0x7f717bcb9880
>2014-01-24 09:59:02.615+0000: 25699: debug : virNetClientIOEventLoopPassTheBuck:1434 : No thread to pass the buck to
>2014-01-24 09:59:02.615+0000: 25699: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1
>2014-01-24 09:59:02.615+0000: 25699: debug : virEventPollInterruptLocked:717 : Interrupting
>2014-01-24 09:59:02.615+0000: 25699: debug : virNetClientIO:1803 : All done with our call head=(nil) call=0x7f717bcb9880 rv=0
>2014-01-24 09:59:02.615+0000: 25699: debug : virNetMessageFree:72 : msg=0x7f717bcb9770 nfds=0 cb=(nil)
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
>2014-01-24 09:59:02.615+0000: 25699: debug : doRemoteOpen:876 : Auto-probed URI is qemu:///session
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollDispatchHandles:471 : Dispatch 1
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollDispatchHandles:485 : i=0 w=1
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1244 : driver 6 remote returned SUCCESS
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1270 : network driver 0 Test returned DECLINED
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1270 : network driver 1 ESX returned DECLINED
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1270 : network driver 2 Hyper-V returned DECLINED
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventRunDefaultImpl:271 : running default event implementation
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1270 : network driver 3 remote returned SUCCESS
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1286 : interface driver 0 Test returned DECLINED
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1286 : interface driver 1 PHYP returned DECLINED
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1286 : interface driver 2 ESX returned DECLINED
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1286 : interface driver 3 Hyper-V returned DECLINED
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=1 d=0
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1286 : interface driver 4 remote returned SUCCESS
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1303 : storage driver 0 Test returned DECLINED
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollCalculateTimeout:364 : No timeout is pending
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=2 timeout=-1
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1303 : storage driver 1 PHYP returned DECLINED
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1303 : storage driver 2 ESX returned DECLINED
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1303 : storage driver 3 Hyper-V returned DECLINED
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1303 : storage driver 4 remote returned SUCCESS
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1320 : node driver 0 Test returned DECLINED
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1320 : node driver 1 ESX returned DECLINED
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1320 : node driver 2 Hyper-V returned DECLINED
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1320 : node driver 3 remote returned SUCCESS
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1337 : secret driver 0 Test returned DECLINED
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1337 : secret driver 1 ESX returned DECLINED
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1337 : secret driver 2 Hyper-V returned DECLINED
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1337 : secret driver 3 remote returned SUCCESS
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1354 : nwfilter driver 0 Test returned DECLINED
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1354 : nwfilter driver 1 ESX returned DECLINED
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1354 : nwfilter driver 2 Hyper-V returned DECLINED
>2014-01-24 09:59:02.615+0000: 25699: debug : do_open:1354 : nwfilter driver 3 remote returned SUCCESS
>2014-01-24 09:59:02.615+0000: 25699: debug : virConnectRegisterCloseCallback:21432 : conn=0x7f717bcb83a0
>2014-01-24 09:59:02.615+0000: 25699: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f717bcb83a0
>2014-01-24 09:59:02.615+0000: 25699: debug : virConnectIsAlive:21380 : conn=0x7f717bcb83a0
>2014-01-24 09:59:02.615+0000: 25699: debug : virConnectGetCapabilities:7577 : conn=0x7f717bcb83a0
>2014-01-24 09:59:02.615+0000: 25699: debug : virNetMessageNew:44 : msg=0x7f717bcb9e10 tracked=0
>2014-01-24 09:59:02.615+0000: 25699: debug : virNetMessageEncodePayload:373 : Encode length as 28
>2014-01-24 09:59:02.615+0000: 25699: debug : virNetClientSendInternal:1952 : RPC_CLIENT_MSG_TX_QUEUE: client=0x7f717bcb9a70 len=28 prog=536903814 vers=1 proc=7 type=0 status=0 serial=4
>2014-01-24 09:59:02.615+0000: 25699: debug : virNetClientCallNew:1905 : New call 0x7f717bcb9d60: msg=0x7f717bcb9e10, expectReply=1, nonBlock=0
>2014-01-24 09:59:02.615+0000: 25699: debug : virNetClientIO:1714 : Outgoing message prog=536903814 version=1 serial=4 proc=7 type=0 length=28 dispatch=(nil)
>2014-01-24 09:59:02.615+0000: 25699: debug : virNetClientIO:1773 : We have the buck head=0x7f717bcb9d60 call=0x7f717bcb9d60
>2014-01-24 09:59:02.615+0000: 25699: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0
>2014-01-24 09:59:02.615+0000: 25699: debug : virEventPollInterruptLocked:717 : Interrupting
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollDispatchHandles:471 : Dispatch 2
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollDispatchHandles:485 : i=0 w=1
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventRunDefaultImpl:271 : running default event implementation
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=0 d=0
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollCalculateTimeout:364 : No timeout is pending
>2014-01-24 09:59:02.615+0000: 25700: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=1 timeout=-1
>2014-01-24 09:59:07.617+0000: 25699: debug : virNetMessageDecodeLength:149 : Got length, now need 28 total (24 more)
>2014-01-24 09:59:07.618+0000: 25699: debug : virNetClientCallDispatch:1123 : RPC_CLIENT_MSG_RX: client=0x7f717bcb9a70 len=28 prog=1801807216 vers=1 proc=1 type=2 status=0 serial=0
>2014-01-24 09:59:07.618+0000: 25699: debug : virKeepAliveCheckMessage:374 : ka=0x7f717bcb8800, client=0x7f717bcb9a70, msg=0x7f717bcb9ad8
>2014-01-24 09:59:07.618+0000: 25699: debug : virKeepAliveCheckMessage:391 : RPC_KEEPALIVE_RECEIVED: ka=0x7f717bcb8800 client=0x7f717bcb9a70 prog=1801807216 vers=1 proc=1
>2014-01-24 09:59:07.618+0000: 25699: debug : virKeepAliveCheckMessage:395 : Got keepalive request from client 0x7f717bcb9a70
>2014-01-24 09:59:07.618+0000: 25699: debug : virNetMessageNew:44 : msg=0x7f717bcb8940 tracked=0
>2014-01-24 09:59:07.618+0000: 25699: debug : virNetMessageEncodePayloadEmpty:473 : Encode length as 28
>2014-01-24 09:59:07.618+0000: 25699: debug : virKeepAliveMessage:101 : Sending keepalive response to client 0x7f717bcb9a70
>2014-01-24 09:59:07.618+0000: 25699: debug : virKeepAliveMessage:104 : RPC_KEEPALIVE_SEND: ka=0x7f717bcb8800 client=0x7f717bcb9a70 prog=1801807216 vers=1 proc=2
>2014-01-24 09:59:07.618+0000: 25699: debug : virNetClientQueueNonBlocking:1926 : RPC_CLIENT_MSG_TX_QUEUE: client=0x7f717bcb9a70 len=28 prog=1801807216 vers=1 proc=2 type=2 status=0 serial=0
>2014-01-24 09:59:07.618+0000: 25699: debug : virNetClientCallNew:1905 : New call 0x7f717bcb9c10: msg=0x7f717bcb8940, expectReply=0, nonBlock=1
>2014-01-24 09:59:07.618+0000: 25699: debug : virNetMessageClear:55 : msg=0x7f717bcb9ad8 nfds=0
>2014-01-24 09:59:07.618+0000: 25699: debug : virNetClientIOEventLoopRemoveDone:1379 : Removing completed call 0x7f717bcb9c10
>2014-01-24 09:59:12.623+0000: 25699: debug : virNetMessageDecodeLength:149 : Got length, now need 28 total (24 more)
>2014-01-24 09:59:12.623+0000: 25699: debug : virNetClientCallDispatch:1123 : RPC_CLIENT_MSG_RX: client=0x7f717bcb9a70 len=28 prog=1801807216 vers=1 proc=1 type=2 status=0 serial=0
>2014-01-24 09:59:12.624+0000: 25699: debug : virKeepAliveCheckMessage:374 : ka=0x7f717bcb8800, client=0x7f717bcb9a70, msg=0x7f717bcb9ad8
>2014-01-24 09:59:12.624+0000: 25699: debug : virKeepAliveCheckMessage:391 : RPC_KEEPALIVE_RECEIVED: ka=0x7f717bcb8800 client=0x7f717bcb9a70 prog=1801807216 vers=1 proc=1
>2014-01-24 09:59:12.624+0000: 25699: debug : virKeepAliveCheckMessage:395 : Got keepalive request from client 0x7f717bcb9a70
>2014-01-24 09:59:12.624+0000: 25699: debug : virNetMessageNew:44 : msg=0x7f717bcb9890 tracked=0
>2014-01-24 09:59:12.624+0000: 25699: debug : virNetMessageEncodePayloadEmpty:473 : Encode length as 28
>2014-01-24 09:59:12.624+0000: 25699: debug : virKeepAliveMessage:101 : Sending keepalive response to client 0x7f717bcb9a70
>2014-01-24 09:59:12.624+0000: 25699: debug : virKeepAliveMessage:104 : RPC_KEEPALIVE_SEND: ka=0x7f717bcb8800 client=0x7f717bcb9a70 prog=1801807216 vers=1 proc=2
>2014-01-24 09:59:12.624+0000: 25699: debug : virNetClientQueueNonBlocking:1926 : RPC_CLIENT_MSG_TX_QUEUE: client=0x7f717bcb9a70 len=28 prog=1801807216 vers=1 proc=2 type=2 status=0 serial=0
>2014-01-24 09:59:12.624+0000: 25699: debug : virNetClientCallNew:1905 : New call 0x7f717bcb9c10: msg=0x7f717bcb9890, expectReply=0, nonBlock=1
>2014-01-24 09:59:12.624+0000: 25699: debug : virNetMessageClear:55 : msg=0x7f717bcb9ad8 nfds=0
>2014-01-24 09:59:12.624+0000: 25699: debug : virNetClientIOEventLoopRemoveDone:1379 : Removing completed call 0x7f717bcb9c10

Actions: View

Attachments on bug 1057535: 854872