Attachment 869011 Details for Bug 1071264 – stderr of 'virsh snapshot-revert' with LIBVIRT_DEBUG=1

stderr of 'virsh snapshot-revert' with LIBVIRT_DEBUG=1

stderr-of-snapshot-revert-LIBVIRT_DEBUG.txt (text/plain), 64.14 KB, created by Kashyap Chamarthy on 2014-02-28 12:09:00 UTC

(hide)

Description:

Filename:

MIME Type:

Creator: Kashyap Chamarthy

Created: 2014-02-28 12:09:00 UTC

Size: 64.14 KB

patch

obsolete

>2014-02-28 17:07:04.098+0000: 5949: info : libvirt version: 1.2.1, package: 2.fc21 (Fedora Project, 2014-01-27-18:29:53, buildvm-18.phx2.fedoraproject.org)
>2014-02-28 17:07:04.098+0000: 5949: debug : virGlobalInit:394 : register drivers
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterDriver:712 : driver=0x7ff9ca0030a0 name=Test
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterDriver:724 : registering Test as driver 0
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterNetworkDriver:554 : registering Test as network driver 0
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterInterfaceDriver:582 : registering Test as interface driver 0
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterStorageDriver:610 : registering Test as storage driver 0
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterNodeDeviceDriver:638 : registering Test as device driver 0
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterSecretDriver:666 : registering Test as secret driver 0
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterNWFilterDriver:694 : registering Test as network filter driver 0
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterDriver:712 : driver=0x7ff9ca004c00 name=OPENVZ
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterDriver:724 : registering OPENVZ as driver 1
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterDriver:712 : driver=0x7ff9ca005260 name=VMWARE
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterDriver:724 : registering VMWARE as driver 2
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterDriver:712 : driver=0x7ff9ca0045c0 name=PHYP
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterDriver:724 : registering PHYP as driver 3
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterStorageDriver:610 : registering PHYP as storage driver 1
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterInterfaceDriver:582 : registering PHYP as interface driver 1
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterDriver:712 : driver=0x7ff9ca005880 name=ESX
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterDriver:724 : registering ESX as driver 4
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterInterfaceDriver:582 : registering ESX as interface driver 2
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterNetworkDriver:554 : registering ESX as network driver 1
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterStorageDriver:610 : registering ESX as storage driver 2
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterNodeDeviceDriver:638 : registering ESX as device driver 1
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterSecretDriver:666 : registering ESX as secret driver 1
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterNWFilterDriver:694 : registering ESX as network filter driver 1
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterDriver:712 : driver=0x7ff9ca006580 name=Hyper-V
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterDriver:724 : registering Hyper-V as driver 5
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterInterfaceDriver:582 : registering Hyper-V as interface driver 3
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterNetworkDriver:554 : registering Hyper-V as network driver 2
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterStorageDriver:610 : registering Hyper-V as storage driver 3
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterNodeDeviceDriver:638 : registering Hyper-V as device driver 2
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterSecretDriver:666 : registering Hyper-V as secret driver 2
>2014-02-28 17:07:04.098+0000: 5949: debug : virRegisterNWFilterDriver:694 : registering Hyper-V as network filter driver 2
>2014-02-28 17:07:04.098+0000: 5949: debug : parallelsRegister:2423 : Can't find prlctl command in the PATH env
>2014-02-28 17:07:04.099+0000: 5949: debug : virRegisterDriver:712 : driver=0x7ff9ca003b20 name=remote
>2014-02-28 17:07:04.099+0000: 5949: debug : virRegisterDriver:724 : registering remote as driver 6
>2014-02-28 17:07:04.099+0000: 5949: debug : virRegisterNetworkDriver:554 : registering remote as network driver 3
>2014-02-28 17:07:04.099+0000: 5949: debug : virRegisterInterfaceDriver:582 : registering remote as interface driver 4
>2014-02-28 17:07:04.099+0000: 5949: debug : virRegisterStorageDriver:610 : registering remote as storage driver 4
>2014-02-28 17:07:04.099+0000: 5949: debug : virRegisterNodeDeviceDriver:638 : registering remote as device driver 3
>2014-02-28 17:07:04.099+0000: 5949: debug : virRegisterSecretDriver:666 : registering remote as secret driver 3
>2014-02-28 17:07:04.099+0000: 5949: debug : virRegisterNWFilterDriver:694 : registering remote as network filter driver 3
>2014-02-28 17:07:04.099+0000: 5949: debug : virEventRegisterDefaultImpl:259 : registering default event implementation
>2014-02-28 17:07:04.099+0000: 5949: debug : virEventPollAddHandle:111 : Used 0 handle slots, adding at least 10 more
>2014-02-28 17:07:04.099+0000: 5949: debug : virEventPollInterruptLocked:713 : Skip interrupt, 0 0
>2014-02-28 17:07:04.099+0000: 5949: debug : virEventPollAddHandle:136 : EVENT_POLL_ADD_HANDLE: watch=1 fd=4 events=1 cb=0x7ff9c9b511b0 opaque=(nil) ff=(nil)
>2014-02-28 17:07:04.099+0000: 5949: debug : virEventRegisterImpl:229 : addHandle=0x7ff9c9b518e0 updateHandle=0x7ff9c9b51c30 removeHandle=0x7ff9c9b51210 addTimeout=0x7ff9c9b51380 updateTimeout=0x7ff9c9b51590 removeTimeout=0x7ff9c9b51760
>2014-02-28 17:07:04.099+0000: 5949: debug : virConnectOpenAuth:1432 : name=(null), auth=0x7ff9ca002be0, flags=0
>2014-02-28 17:07:04.099+0000: 5949: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7ff9caf51540 classname=virConnect
>2014-02-28 17:07:04.099+0000: 5949: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7ff9caf516e0 classname=virConnectCloseCallbackData
>2014-02-28 17:07:04.099+0000: 5949: debug : virConnectGetConfigFile:962 : Loading config file '/etc/libvirt/libvirt.conf'
>2014-02-28 17:07:04.099+0000: 5949: debug : virConfReadFile:748 : filename=/etc/libvirt/libvirt.conf
>2014-02-28 17:07:04.099+0000: 5949: debug : virFileClose:90 : Closed fd 6
>2014-02-28 17:07:04.099+0000: 5949: debug : do_open:1145 : no name, allowing driver auto-select
>2014-02-28 17:07:04.099+0000: 5949: debug : do_open:1187 : trying driver 0 (Test) ...
>2014-02-28 17:07:04.099+0000: 5951: debug : virEventRunDefaultImpl:303 : running default event implementation
>2014-02-28 17:07:04.099+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.099+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.099+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 1
>2014-02-28 17:07:04.099+0000: 5949: debug : do_open:1194 : driver 0 Test returned DECLINED
>2014-02-28 17:07:04.099+0000: 5949: debug : do_open:1187 : trying driver 1 (OPENVZ) ...
>2014-02-28 17:07:04.099+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
>2014-02-28 17:07:04.099+0000: 5951: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
>2014-02-28 17:07:04.099+0000: 5951: debug : virEventPollCalculateTimeout:364 : No timeout is pending
>2014-02-28 17:07:04.099+0000: 5949: debug : do_open:1194 : driver 1 OPENVZ returned DECLINED
>2014-02-28 17:07:04.099+0000: 5949: debug : do_open:1187 : trying driver 2 (VMWARE) ...
>2014-02-28 17:07:04.099+0000: 5951: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=1 timeout=-1
>2014-02-28 17:07:04.099+0000: 5949: debug : do_open:1194 : driver 2 VMWARE returned DECLINED
>2014-02-28 17:07:04.099+0000: 5949: debug : do_open:1187 : trying driver 3 (PHYP) ...
>2014-02-28 17:07:04.099+0000: 5949: debug : do_open:1194 : driver 3 PHYP returned DECLINED
>2014-02-28 17:07:04.099+0000: 5949: debug : do_open:1187 : trying driver 4 (ESX) ...
>2014-02-28 17:07:04.099+0000: 5949: debug : do_open:1194 : driver 4 ESX returned DECLINED
>2014-02-28 17:07:04.099+0000: 5949: debug : do_open:1187 : trying driver 5 (Hyper-V) ...
>2014-02-28 17:07:04.099+0000: 5949: debug : do_open:1194 : driver 5 Hyper-V returned DECLINED
>2014-02-28 17:07:04.100+0000: 5949: debug : do_open:1187 : trying driver 6 (remote) ...
>2014-02-28 17:07:04.100+0000: 5949: debug : remoteConnectOpen:1028 : Auto-probe remote URI
>2014-02-28 17:07:04.100+0000: 5949: debug : doRemoteOpen:623 : proceeding with name = 
>2014-02-28 17:07:04.100+0000: 5949: debug : doRemoteOpen:632 : Connecting with transport 1
>2014-02-28 17:07:04.100+0000: 5949: debug : doRemoteOpen:723 : Proceeding with sockname /var/run/libvirt/libvirt-sock
>2014-02-28 17:07:04.100+0000: 5949: debug : virNetSocketNew:155 : localAddr=0x7fff5d20f910 remoteAddr=0x7fff5d20f9a0 fd=6 errfd=-1 pid=0
>2014-02-28 17:07:04.100+0000: 5949: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7ff9caf52210 classname=virNetSocket
>2014-02-28 17:07:04.100+0000: 5949: debug : virNetSocketNew:205 : RPC_SOCKET_NEW: sock=0x7ff9caf52210 fd=6 errfd=-1 pid=0 localAddr=127.0.0.1;0, remoteAddr=127.0.0.1;0
>2014-02-28 17:07:04.100+0000: 5949: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7ff9caf524d0 classname=virNetClient
>2014-02-28 17:07:04.100+0000: 5949: debug : virNetClientNew:326 : RPC_CLIENT_NEW: client=0x7ff9caf524d0 sock=0x7ff9caf52210
>2014-02-28 17:07:04.100+0000: 5949: debug : virObjectRef:293 : OBJECT_REF: obj=0x7ff9caf524d0
>2014-02-28 17:07:04.100+0000: 5949: debug : virObjectRef:293 : OBJECT_REF: obj=0x7ff9caf52210
>2014-02-28 17:07:04.100+0000: 5949: debug : virEventPollInterruptLocked:717 : Interrupting
>2014-02-28 17:07:04.100+0000: 5949: debug : virEventPollAddHandle:136 : EVENT_POLL_ADD_HANDLE: watch=2 fd=6 events=1 cb=0x7ff9c9c79b60 opaque=0x7ff9caf52210 ff=0x7ff9c9c79b10
>2014-02-28 17:07:04.100+0000: 5949: debug : virKeepAliveNew:196 : client=0x7ff9caf524d0, interval=-1, count=0
>2014-02-28 17:07:04.100+0000: 5949: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7ff9caf527c0 classname=virKeepAlive
>2014-02-28 17:07:04.100+0000: 5949: debug : virKeepAliveNew:215 : RPC_KEEPALIVE_NEW: ka=0x7ff9caf527c0 client=0x7ff9caf524d0
>2014-02-28 17:07:04.100+0000: 5949: debug : virObjectRef:293 : OBJECT_REF: obj=0x7ff9caf524d0
>2014-02-28 17:07:04.100+0000: 5949: debug : virObjectRef:293 : OBJECT_REF: obj=0x7ff9caf516e0
>2014-02-28 17:07:04.100+0000: 5951: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
>2014-02-28 17:07:04.100+0000: 5951: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
>2014-02-28 17:07:04.100+0000: 5949: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7ff9caf526e0 classname=virNetClientProgram
>2014-02-28 17:07:04.100+0000: 5951: debug : virEventPollDispatchHandles:471 : Dispatch 1
>2014-02-28 17:07:04.100+0000: 5949: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7ff9caf523f0 classname=virNetClientProgram
>2014-02-28 17:07:04.100+0000: 5951: debug : virEventPollDispatchHandles:485 : i=0 w=1
>2014-02-28 17:07:04.100+0000: 5949: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7ff9caf51fe0 classname=virNetClientProgram
>2014-02-28 17:07:04.100+0000: 5951: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-02-28 17:07:04.100+0000: 5949: debug : virObjectRef:293 : OBJECT_REF: obj=0x7ff9caf526e0
>2014-02-28 17:07:04.100+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.100+0000: 5949: debug : virObjectRef:293 : OBJECT_REF: obj=0x7ff9caf523f0
>2014-02-28 17:07:04.100+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.100+0000: 5949: debug : virObjectRef:293 : OBJECT_REF: obj=0x7ff9caf51fe0
>2014-02-28 17:07:04.100+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.100+0000: 5949: debug : doRemoteOpen:841 : Trying authentication
>2014-02-28 17:07:04.100+0000: 5951: debug : virEventRunDefaultImpl:303 : running default event implementation
>2014-02-28 17:07:04.100+0000: 5949: debug : virNetMessageNew:44 : msg=0x7ff9caf52cf0 tracked=0
>2014-02-28 17:07:04.100+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.100+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.100+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.100+0000: 5949: debug : virNetMessageEncodePayload:374 : Encode length as 28
>2014-02-28 17:07:04.100+0000: 5949: debug : virNetClientSendInternal:1952 : RPC_CLIENT_MSG_TX_QUEUE: client=0x7ff9caf524d0 len=28 prog=536903814 vers=1 proc=66 type=0 status=0 serial=0
>2014-02-28 17:07:04.100+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
>2014-02-28 17:07:04.100+0000: 5949: debug : virNetClientCallNew:1905 : New call 0x7ff9caf52680: msg=0x7ff9caf52cf0, expectReply=1, nonBlock=0
>2014-02-28 17:07:04.100+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=1 d=0
>2014-02-28 17:07:04.100+0000: 5949: debug : virNetClientIO:1714 : Outgoing message prog=536903814 version=1 serial=0 proc=66 type=0 length=28 dispatch=(nil)
>2014-02-28 17:07:04.100+0000: 5951: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
>2014-02-28 17:07:04.100+0000: 5949: debug : virNetClientIO:1773 : We have the buck head=0x7ff9caf52680 call=0x7ff9caf52680
>2014-02-28 17:07:04.100+0000: 5951: debug : virEventPollCalculateTimeout:364 : No timeout is pending
>2014-02-28 17:07:04.100+0000: 5949: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0
>2014-02-28 17:07:04.100+0000: 5951: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=2 timeout=-1
>2014-02-28 17:07:04.100+0000: 5949: debug : virEventPollInterruptLocked:717 : Interrupting
>2014-02-28 17:07:04.101+0000: 5951: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
>2014-02-28 17:07:04.101+0000: 5951: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
>2014-02-28 17:07:04.101+0000: 5951: debug : virEventPollDispatchHandles:471 : Dispatch 2
>2014-02-28 17:07:04.101+0000: 5951: debug : virEventPollDispatchHandles:485 : i=0 w=1
>2014-02-28 17:07:04.101+0000: 5951: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-02-28 17:07:04.101+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.101+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.101+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.101+0000: 5951: debug : virEventRunDefaultImpl:303 : running default event implementation
>2014-02-28 17:07:04.101+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.101+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.101+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.101+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
>2014-02-28 17:07:04.101+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=0 d=0
>2014-02-28 17:07:04.101+0000: 5951: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
>2014-02-28 17:07:04.101+0000: 5951: debug : virEventPollCalculateTimeout:364 : No timeout is pending
>2014-02-28 17:07:04.101+0000: 5951: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=1 timeout=-1
>2014-02-28 17:07:04.102+0000: 5949: debug : virNetMessageDecodeLength:149 : Got length, now need 36 total (32 more)
>2014-02-28 17:07:04.102+0000: 5949: debug : virNetClientCallDispatch:1123 : RPC_CLIENT_MSG_RX: client=0x7ff9caf524d0 len=36 prog=536903814 vers=1 proc=66 type=1 status=0 serial=0
>2014-02-28 17:07:04.102+0000: 5949: debug : virKeepAliveCheckMessage:374 : ka=0x7ff9caf527c0, client=0x7ff9caf524d0, msg=0x7ff9caf52538
>2014-02-28 17:07:04.102+0000: 5949: debug : virNetMessageClear:55 : msg=0x7ff9caf52538 nfds=0
>2014-02-28 17:07:04.102+0000: 5949: debug : virNetClientIOEventLoopPassTheBuck:1420 : Giving up the buck 0x7ff9caf52680
>2014-02-28 17:07:04.102+0000: 5949: debug : virNetClientIOEventLoopPassTheBuck:1434 : No thread to pass the buck to
>2014-02-28 17:07:04.102+0000: 5949: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1
>2014-02-28 17:07:04.102+0000: 5949: debug : virEventPollInterruptLocked:717 : Interrupting
>2014-02-28 17:07:04.102+0000: 5949: debug : virNetClientIO:1803 : All done with our call head=(nil) call=0x7ff9caf52680 rv=0
>2014-02-28 17:07:04.102+0000: 5949: debug : virNetMessageFree:72 : msg=0x7ff9caf52cf0 nfds=0 cb=(nil)
>2014-02-28 17:07:04.102+0000: 5949: debug : virNetMessageNew:44 : msg=0x7ff9caf52cf0 tracked=0
>2014-02-28 17:07:04.102+0000: 5949: debug : virNetMessageEncodePayload:374 : Encode length as 32
>2014-02-28 17:07:04.102+0000: 5949: debug : virNetClientSendInternal:1952 : RPC_CLIENT_MSG_TX_QUEUE: client=0x7ff9caf524d0 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=1
>2014-02-28 17:07:04.102+0000: 5949: debug : virNetClientCallNew:1905 : New call 0x7ff9caf50e70: msg=0x7ff9caf52cf0, expectReply=1, nonBlock=0
>2014-02-28 17:07:04.102+0000: 5949: debug : virNetClientIO:1714 : Outgoing message prog=536903814 version=1 serial=1 proc=60 type=0 length=32 dispatch=(nil)
>2014-02-28 17:07:04.102+0000: 5949: debug : virNetClientIO:1773 : We have the buck head=0x7ff9caf50e70 call=0x7ff9caf50e70
>2014-02-28 17:07:04.102+0000: 5949: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0
>2014-02-28 17:07:04.102+0000: 5949: debug : virEventPollInterruptLocked:717 : Interrupting
>2014-02-28 17:07:04.102+0000: 5951: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
>2014-02-28 17:07:04.102+0000: 5951: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
>2014-02-28 17:07:04.102+0000: 5951: debug : virEventPollDispatchHandles:471 : Dispatch 1
>2014-02-28 17:07:04.102+0000: 5951: debug : virEventPollDispatchHandles:485 : i=0 w=1
>2014-02-28 17:07:04.102+0000: 5951: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventRunDefaultImpl:303 : running default event implementation
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=0 d=0
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollCalculateTimeout:364 : No timeout is pending
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=1 timeout=-1
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollDispatchHandles:471 : Dispatch 1
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollDispatchHandles:485 : i=0 w=1
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventRunDefaultImpl:303 : running default event implementation
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=0 d=0
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollCalculateTimeout:364 : No timeout is pending
>2014-02-28 17:07:04.103+0000: 5951: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=1 timeout=-1
>2014-02-28 17:07:04.103+0000: 5949: debug : virNetMessageDecodeLength:149 : Got length, now need 32 total (28 more)
>2014-02-28 17:07:04.103+0000: 5949: debug : virNetClientCallDispatch:1123 : RPC_CLIENT_MSG_RX: client=0x7ff9caf524d0 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=1
>2014-02-28 17:07:04.103+0000: 5949: debug : virKeepAliveCheckMessage:374 : ka=0x7ff9caf527c0, client=0x7ff9caf524d0, msg=0x7ff9caf52538
>2014-02-28 17:07:04.103+0000: 5949: debug : virNetMessageClear:55 : msg=0x7ff9caf52538 nfds=0
>2014-02-28 17:07:04.103+0000: 5949: debug : virNetClientIOEventLoopPassTheBuck:1420 : Giving up the buck 0x7ff9caf50e70
>2014-02-28 17:07:04.103+0000: 5949: debug : virNetClientIOEventLoopPassTheBuck:1434 : No thread to pass the buck to
>2014-02-28 17:07:04.103+0000: 5949: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1
>2014-02-28 17:07:04.103+0000: 5949: debug : virEventPollInterruptLocked:717 : Interrupting
>2014-02-28 17:07:04.103+0000: 5949: debug : virNetClientIO:1803 : All done with our call head=(nil) call=0x7ff9caf50e70 rv=0
>2014-02-28 17:07:04.104+0000: 5949: debug : virNetMessageFree:72 : msg=0x7ff9caf52cf0 nfds=0 cb=(nil)
>2014-02-28 17:07:04.104+0000: 5949: debug : doRemoteOpen:867 : Trying to open URI 
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
>2014-02-28 17:07:04.104+0000: 5949: debug : virNetMessageNew:44 : msg=0x7ff9caf50e70 tracked=0
>2014-02-28 17:07:04.104+0000: 5949: debug : virNetMessageEncodePayload:374 : Encode length as 40
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollDispatchHandles:471 : Dispatch 1
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollDispatchHandles:485 : i=0 w=1
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-02-28 17:07:04.104+0000: 5949: debug : virNetClientSendInternal:1952 : RPC_CLIENT_MSG_TX_QUEUE: client=0x7ff9caf524d0 len=40 prog=536903814 vers=1 proc=1 type=0 status=0 serial=2
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.104+0000: 5949: debug : virNetClientCallNew:1905 : New call 0x7ff9caf52850: msg=0x7ff9caf50e70, expectReply=1, nonBlock=0
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.104+0000: 5949: debug : virNetClientIO:1714 : Outgoing message prog=536903814 version=1 serial=2 proc=1 type=0 length=40 dispatch=(nil)
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.104+0000: 5949: debug : virNetClientIO:1773 : We have the buck head=0x7ff9caf52850 call=0x7ff9caf52850
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventRunDefaultImpl:303 : running default event implementation
>2014-02-28 17:07:04.104+0000: 5949: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=1 d=0
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollCalculateTimeout:364 : No timeout is pending
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=2 timeout=-1
>2014-02-28 17:07:04.104+0000: 5949: debug : virEventPollInterruptLocked:717 : Interrupting
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollDispatchHandles:471 : Dispatch 2
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollDispatchHandles:485 : i=0 w=1
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventRunDefaultImpl:303 : running default event implementation
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=0 d=0
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollCalculateTimeout:364 : No timeout is pending
>2014-02-28 17:07:04.104+0000: 5951: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=1 timeout=-1
>2014-02-28 17:07:04.106+0000: 5949: debug : virNetMessageDecodeLength:149 : Got length, now need 28 total (24 more)
>2014-02-28 17:07:04.106+0000: 5949: debug : virNetClientCallDispatch:1123 : RPC_CLIENT_MSG_RX: client=0x7ff9caf524d0 len=28 prog=536903814 vers=1 proc=1 type=1 status=0 serial=2
>2014-02-28 17:07:04.106+0000: 5949: debug : virKeepAliveCheckMessage:374 : ka=0x7ff9caf527c0, client=0x7ff9caf524d0, msg=0x7ff9caf52538
>2014-02-28 17:07:04.106+0000: 5949: debug : virNetMessageClear:55 : msg=0x7ff9caf52538 nfds=0
>2014-02-28 17:07:04.106+0000: 5949: debug : virNetClientIOEventLoopPassTheBuck:1420 : Giving up the buck 0x7ff9caf52850
>2014-02-28 17:07:04.106+0000: 5949: debug : virNetClientIOEventLoopPassTheBuck:1434 : No thread to pass the buck to
>2014-02-28 17:07:04.106+0000: 5949: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1
>2014-02-28 17:07:04.106+0000: 5949: debug : virEventPollInterruptLocked:717 : Interrupting
>2014-02-28 17:07:04.106+0000: 5949: debug : virNetClientIO:1803 : All done with our call head=(nil) call=0x7ff9caf52850 rv=0
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
>2014-02-28 17:07:04.106+0000: 5949: debug : virNetMessageFree:72 : msg=0x7ff9caf50e70 nfds=0 cb=(nil)
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollDispatchHandles:471 : Dispatch 1
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollDispatchHandles:485 : i=0 w=1
>2014-02-28 17:07:04.106+0000: 5949: debug : doRemoteOpen:878 : Trying to query remote URI
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.106+0000: 5949: debug : virNetMessageNew:44 : msg=0x7ff9caf529e0 tracked=0
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.106+0000: 5949: debug : virNetMessageEncodePayload:374 : Encode length as 28
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.106+0000: 5949: debug : virNetClientSendInternal:1952 : RPC_CLIENT_MSG_TX_QUEUE: client=0x7ff9caf524d0 len=28 prog=536903814 vers=1 proc=110 type=0 status=0 serial=3
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventRunDefaultImpl:303 : running default event implementation
>2014-02-28 17:07:04.106+0000: 5949: debug : virNetClientCallNew:1905 : New call 0x7ff9caf52680: msg=0x7ff9caf529e0, expectReply=1, nonBlock=0
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.106+0000: 5949: debug : virNetClientIO:1714 : Outgoing message prog=536903814 version=1 serial=3 proc=110 type=0 length=28 dispatch=(nil)
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.106+0000: 5949: debug : virNetClientIO:1773 : We have the buck head=0x7ff9caf52680 call=0x7ff9caf52680
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.106+0000: 5949: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=1 d=0
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollCalculateTimeout:364 : No timeout is pending
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=2 timeout=-1
>2014-02-28 17:07:04.106+0000: 5949: debug : virEventPollInterruptLocked:717 : Interrupting
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollDispatchHandles:471 : Dispatch 2
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollDispatchHandles:485 : i=0 w=1
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventRunDefaultImpl:303 : running default event implementation
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=0 d=0
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollCalculateTimeout:364 : No timeout is pending
>2014-02-28 17:07:04.106+0000: 5951: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=1 timeout=-1
>2014-02-28 17:07:04.107+0000: 5949: debug : virNetMessageDecodeLength:149 : Got length, now need 48 total (44 more)
>2014-02-28 17:07:04.107+0000: 5949: debug : virNetClientCallDispatch:1123 : RPC_CLIENT_MSG_RX: client=0x7ff9caf524d0 len=48 prog=536903814 vers=1 proc=110 type=1 status=0 serial=3
>2014-02-28 17:07:04.107+0000: 5949: debug : virKeepAliveCheckMessage:374 : ka=0x7ff9caf527c0, client=0x7ff9caf524d0, msg=0x7ff9caf52538
>2014-02-28 17:07:04.107+0000: 5949: debug : virNetMessageClear:55 : msg=0x7ff9caf52538 nfds=0
>2014-02-28 17:07:04.107+0000: 5949: debug : virNetClientIOEventLoopPassTheBuck:1420 : Giving up the buck 0x7ff9caf52680
>2014-02-28 17:07:04.107+0000: 5949: debug : virNetClientIOEventLoopPassTheBuck:1434 : No thread to pass the buck to
>2014-02-28 17:07:04.107+0000: 5949: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1
>2014-02-28 17:07:04.107+0000: 5949: debug : virEventPollInterruptLocked:717 : Interrupting
>2014-02-28 17:07:04.107+0000: 5949: debug : virNetClientIO:1803 : All done with our call head=(nil) call=0x7ff9caf52680 rv=0
>2014-02-28 17:07:04.107+0000: 5949: debug : virNetMessageFree:72 : msg=0x7ff9caf529e0 nfds=0 cb=(nil)
>2014-02-28 17:07:04.107+0000: 5949: debug : doRemoteOpen:886 : Auto-probed URI is qemu:///system
>2014-02-28 17:07:04.108+0000: 5951: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1194 : driver 6 remote returned SUCCESS
>2014-02-28 17:07:04.108+0000: 5951: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1220 : network driver 0 Test returned DECLINED
>2014-02-28 17:07:04.108+0000: 5951: debug : virEventPollDispatchHandles:471 : Dispatch 1
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1220 : network driver 1 ESX returned DECLINED
>2014-02-28 17:07:04.108+0000: 5951: debug : virEventPollDispatchHandles:485 : i=0 w=1
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1220 : network driver 2 Hyper-V returned DECLINED
>2014-02-28 17:07:04.108+0000: 5951: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1220 : network driver 3 remote returned SUCCESS
>2014-02-28 17:07:04.108+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1236 : interface driver 0 Test returned DECLINED
>2014-02-28 17:07:04.108+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1236 : interface driver 1 PHYP returned DECLINED
>2014-02-28 17:07:04.108+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1236 : interface driver 2 ESX returned DECLINED
>2014-02-28 17:07:04.108+0000: 5951: debug : virEventRunDefaultImpl:303 : running default event implementation
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1236 : interface driver 3 Hyper-V returned DECLINED
>2014-02-28 17:07:04.108+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.108+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1236 : interface driver 4 remote returned SUCCESS
>2014-02-28 17:07:04.108+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1253 : storage driver 0 Test returned DECLINED
>2014-02-28 17:07:04.108+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1253 : storage driver 1 PHYP returned DECLINED
>2014-02-28 17:07:04.108+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=1 d=0
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1253 : storage driver 2 ESX returned DECLINED
>2014-02-28 17:07:04.108+0000: 5951: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1253 : storage driver 3 Hyper-V returned DECLINED
>2014-02-28 17:07:04.108+0000: 5951: debug : virEventPollCalculateTimeout:364 : No timeout is pending
>2014-02-28 17:07:04.108+0000: 5951: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=2 timeout=-1
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1253 : storage driver 4 remote returned SUCCESS
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1270 : node driver 0 Test returned DECLINED
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1270 : node driver 1 ESX returned DECLINED
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1270 : node driver 2 Hyper-V returned DECLINED
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1270 : node driver 3 remote returned SUCCESS
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1287 : secret driver 0 Test returned DECLINED
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1287 : secret driver 1 ESX returned DECLINED
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1287 : secret driver 2 Hyper-V returned DECLINED
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1287 : secret driver 3 remote returned SUCCESS
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1304 : nwfilter driver 0 Test returned DECLINED
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1304 : nwfilter driver 1 ESX returned DECLINED
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1304 : nwfilter driver 2 Hyper-V returned DECLINED
>2014-02-28 17:07:04.108+0000: 5949: debug : do_open:1304 : nwfilter driver 3 remote returned SUCCESS
>2014-02-28 17:07:04.108+0000: 5949: debug : virConnectRegisterCloseCallback:19942 : conn=0x7ff9caf51540
>2014-02-28 17:07:04.108+0000: 5949: debug : virObjectRef:293 : OBJECT_REF: obj=0x7ff9caf51540
>2014-02-28 17:07:04.108+0000: 5949: debug : virConnectIsAlive:19893 : conn=0x7ff9caf51540
>2014-02-28 17:07:04.108+0000: 5949: debug : virDomainLookupByName:2161 : conn=0x7ff9caf51540, name=devstack1
>2014-02-28 17:07:04.108+0000: 5949: debug : virNetMessageNew:44 : msg=0x7ff9caf53000 tracked=0
>2014-02-28 17:07:04.108+0000: 5949: debug : virNetMessageEncodePayload:374 : Encode length as 44
>2014-02-28 17:07:04.108+0000: 5949: debug : virNetClientSendInternal:1952 : RPC_CLIENT_MSG_TX_QUEUE: client=0x7ff9caf524d0 len=44 prog=536903814 vers=1 proc=23 type=0 status=0 serial=4
>2014-02-28 17:07:04.108+0000: 5949: debug : virNetClientCallNew:1905 : New call 0x7ff9caf520c0: msg=0x7ff9caf53000, expectReply=1, nonBlock=0
>2014-02-28 17:07:04.108+0000: 5949: debug : virNetClientIO:1714 : Outgoing message prog=536903814 version=1 serial=4 proc=23 type=0 length=44 dispatch=(nil)
>2014-02-28 17:07:04.108+0000: 5949: debug : virNetClientIO:1773 : We have the buck head=0x7ff9caf520c0 call=0x7ff9caf520c0
>2014-02-28 17:07:04.108+0000: 5949: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0
>2014-02-28 17:07:04.108+0000: 5949: debug : virEventPollInterruptLocked:717 : Interrupting
>2014-02-28 17:07:04.108+0000: 5951: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
>2014-02-28 17:07:04.108+0000: 5951: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
>2014-02-28 17:07:04.108+0000: 5951: debug : virEventPollDispatchHandles:471 : Dispatch 2
>2014-02-28 17:07:04.108+0000: 5951: debug : virEventPollDispatchHandles:485 : i=0 w=1
>2014-02-28 17:07:04.108+0000: 5951: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-02-28 17:07:04.109+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.109+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.109+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.109+0000: 5951: debug : virEventRunDefaultImpl:303 : running default event implementation
>2014-02-28 17:07:04.109+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.109+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.109+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.109+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
>2014-02-28 17:07:04.109+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=0 d=0
>2014-02-28 17:07:04.109+0000: 5951: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
>2014-02-28 17:07:04.109+0000: 5951: debug : virEventPollCalculateTimeout:364 : No timeout is pending
>2014-02-28 17:07:04.109+0000: 5951: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=1 timeout=-1
>2014-02-28 17:07:04.110+0000: 5949: debug : virNetMessageDecodeLength:149 : Got length, now need 64 total (60 more)
>2014-02-28 17:07:04.110+0000: 5949: debug : virNetClientCallDispatch:1123 : RPC_CLIENT_MSG_RX: client=0x7ff9caf524d0 len=64 prog=536903814 vers=1 proc=23 type=1 status=0 serial=4
>2014-02-28 17:07:04.110+0000: 5949: debug : virKeepAliveCheckMessage:374 : ka=0x7ff9caf527c0, client=0x7ff9caf524d0, msg=0x7ff9caf52538
>2014-02-28 17:07:04.110+0000: 5949: debug : virNetMessageClear:55 : msg=0x7ff9caf52538 nfds=0
>2014-02-28 17:07:04.110+0000: 5949: debug : virNetClientIOEventLoopPassTheBuck:1420 : Giving up the buck 0x7ff9caf520c0
>2014-02-28 17:07:04.110+0000: 5949: debug : virNetClientIOEventLoopPassTheBuck:1434 : No thread to pass the buck to
>2014-02-28 17:07:04.110+0000: 5949: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1
>2014-02-28 17:07:04.110+0000: 5949: debug : virEventPollInterruptLocked:717 : Interrupting
>2014-02-28 17:07:04.110+0000: 5949: debug : virNetClientIO:1803 : All done with our call head=(nil) call=0x7ff9caf520c0 rv=0
>2014-02-28 17:07:04.110+0000: 5949: debug : virNetMessageFree:72 : msg=0x7ff9caf53000 nfds=0 cb=(nil)
>2014-02-28 17:07:04.110+0000: 5949: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7ff9caf514d0 classname=virDomain
>2014-02-28 17:07:04.110+0000: 5949: debug : virObjectRef:293 : OBJECT_REF: obj=0x7ff9caf51540
>2014-02-28 17:07:04.110+0000: 5949: debug : virDomainSnapshotLookupByName:18733 : dom=0x7ff9caf514d0, (VM: name=devstack1, uuid=5baea458-21fd-40d6-95ac-7ef51f095e9e), name=snap1, flags=0
>2014-02-28 17:07:04.110+0000: 5949: debug : virNetMessageNew:44 : msg=0x7ff9caf52de0 tracked=0
>2014-02-28 17:07:04.110+0000: 5951: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
>2014-02-28 17:07:04.110+0000: 5951: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
>2014-02-28 17:07:04.110+0000: 5949: debug : virNetMessageEncodePayload:374 : Encode length as 80
>2014-02-28 17:07:04.110+0000: 5949: debug : virNetClientSendInternal:1952 : RPC_CLIENT_MSG_TX_QUEUE: client=0x7ff9caf524d0 len=80 prog=536903814 vers=1 proc=189 type=0 status=0 serial=5
>2014-02-28 17:07:04.110+0000: 5951: debug : virEventPollDispatchHandles:471 : Dispatch 1
>2014-02-28 17:07:04.110+0000: 5949: debug : virNetClientCallNew:1905 : New call 0x7ff9caf52190: msg=0x7ff9caf52de0, expectReply=1, nonBlock=0
>2014-02-28 17:07:04.110+0000: 5951: debug : virEventPollDispatchHandles:485 : i=0 w=1
>2014-02-28 17:07:04.110+0000: 5949: debug : virNetClientIO:1714 : Outgoing message prog=536903814 version=1 serial=5 proc=189 type=0 length=80 dispatch=(nil)
>2014-02-28 17:07:04.110+0000: 5951: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-02-28 17:07:04.110+0000: 5949: debug : virNetClientIO:1773 : We have the buck head=0x7ff9caf52190 call=0x7ff9caf52190
>2014-02-28 17:07:04.110+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.110+0000: 5949: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0
>2014-02-28 17:07:04.110+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.110+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.110+0000: 5951: debug : virEventRunDefaultImpl:303 : running default event implementation
>2014-02-28 17:07:04.110+0000: 5949: debug : virEventPollInterruptLocked:713 : Skip interrupt, 0 140710552139520
>2014-02-28 17:07:04.110+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.110+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.110+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.110+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
>2014-02-28 17:07:04.110+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=0 d=0
>2014-02-28 17:07:04.110+0000: 5951: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
>2014-02-28 17:07:04.110+0000: 5951: debug : virEventPollCalculateTimeout:364 : No timeout is pending
>2014-02-28 17:07:04.110+0000: 5951: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=1 timeout=-1
>2014-02-28 17:07:04.112+0000: 5949: debug : virNetMessageDecodeLength:149 : Got length, now need 76 total (72 more)
>2014-02-28 17:07:04.112+0000: 5949: debug : virNetClientCallDispatch:1123 : RPC_CLIENT_MSG_RX: client=0x7ff9caf524d0 len=76 prog=536903814 vers=1 proc=189 type=1 status=0 serial=5
>2014-02-28 17:07:04.112+0000: 5949: debug : virKeepAliveCheckMessage:374 : ka=0x7ff9caf527c0, client=0x7ff9caf524d0, msg=0x7ff9caf52538
>2014-02-28 17:07:04.112+0000: 5949: debug : virNetMessageClear:55 : msg=0x7ff9caf52538 nfds=0
>2014-02-28 17:07:04.112+0000: 5949: debug : virNetClientIOEventLoopPassTheBuck:1420 : Giving up the buck 0x7ff9caf52190
>2014-02-28 17:07:04.112+0000: 5949: debug : virNetClientIOEventLoopPassTheBuck:1434 : No thread to pass the buck to
>2014-02-28 17:07:04.112+0000: 5949: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1
>2014-02-28 17:07:04.112+0000: 5949: debug : virEventPollInterruptLocked:717 : Interrupting
>2014-02-28 17:07:04.112+0000: 5949: debug : virNetClientIO:1803 : All done with our call head=(nil) call=0x7ff9caf52190 rv=0
>2014-02-28 17:07:04.112+0000: 5949: debug : virNetMessageFree:72 : msg=0x7ff9caf52de0 nfds=0 cb=(nil)
>2014-02-28 17:07:04.112+0000: 5949: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7ff9caf51f70 classname=virDomainSnapshot
>2014-02-28 17:07:04.112+0000: 5949: debug : virObjectRef:293 : OBJECT_REF: obj=0x7ff9caf514d0
>2014-02-28 17:07:04.112+0000: 5949: debug : virDomainSnapshotGetName:17973 : snapshot=0x7ff9caf51f70
>2014-02-28 17:07:04.112+0000: 5949: debug : virDomainRevertToSnapshot:18996 : snapshot=0x7ff9caf51f70, flags=0
>2014-02-28 17:07:04.112+0000: 5949: debug : virNetMessageNew:44 : msg=0x7ff9caf52670 tracked=0
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
>2014-02-28 17:07:04.112+0000: 5949: debug : virNetMessageEncodePayload:374 : Encode length as 80
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollDispatchHandles:471 : Dispatch 1
>2014-02-28 17:07:04.112+0000: 5949: debug : virNetClientSendInternal:1952 : RPC_CLIENT_MSG_TX_QUEUE: client=0x7ff9caf524d0 len=80 prog=536903814 vers=1 proc=192 type=0 status=0 serial=6
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollDispatchHandles:485 : i=0 w=1
>2014-02-28 17:07:04.112+0000: 5949: debug : virNetClientCallNew:1905 : New call 0x7ff9caf520c0: msg=0x7ff9caf52670, expectReply=1, nonBlock=0
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-02-28 17:07:04.112+0000: 5949: debug : virNetClientIO:1714 : Outgoing message prog=536903814 version=1 serial=6 proc=192 type=0 length=80 dispatch=(nil)
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventRunDefaultImpl:303 : running default event implementation
>2014-02-28 17:07:04.112+0000: 5949: debug : virNetClientIO:1773 : We have the buck head=0x7ff9caf520c0 call=0x7ff9caf520c0
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.112+0000: 5949: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=1 d=0
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollCalculateTimeout:364 : No timeout is pending
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=2 timeout=-1
>2014-02-28 17:07:04.112+0000: 5949: debug : virEventPollInterruptLocked:717 : Interrupting
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollDispatchHandles:471 : Dispatch 2
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollDispatchHandles:485 : i=0 w=1
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventRunDefaultImpl:303 : running default event implementation
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=0 d=0
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollCalculateTimeout:364 : No timeout is pending
>2014-02-28 17:07:04.112+0000: 5951: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=1 timeout=-1
>2014-02-28 17:07:04.115+0000: 5949: debug : virNetMessageDecodeLength:149 : Got length, now need 120 total (116 more)
>2014-02-28 17:07:04.115+0000: 5949: debug : virNetClientCallDispatch:1123 : RPC_CLIENT_MSG_RX: client=0x7ff9caf524d0 len=120 prog=536903814 vers=1 proc=192 type=1 status=1 serial=6
>2014-02-28 17:07:04.115+0000: 5949: debug : virKeepAliveCheckMessage:374 : ka=0x7ff9caf527c0, client=0x7ff9caf524d0, msg=0x7ff9caf52538
>2014-02-28 17:07:04.115+0000: 5949: debug : virNetMessageClear:55 : msg=0x7ff9caf52538 nfds=0
>2014-02-28 17:07:04.116+0000: 5949: debug : virNetClientIOEventLoopPassTheBuck:1420 : Giving up the buck 0x7ff9caf520c0
>2014-02-28 17:07:04.116+0000: 5949: debug : virNetClientIOEventLoopPassTheBuck:1434 : No thread to pass the buck to
>2014-02-28 17:07:04.116+0000: 5949: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1
>2014-02-28 17:07:04.116+0000: 5949: debug : virEventPollInterruptLocked:717 : Interrupting
>2014-02-28 17:07:04.116+0000: 5949: debug : virNetClientIO:1803 : All done with our call head=(nil) call=0x7ff9caf520c0 rv=0
>2014-02-28 17:07:04.116+0000: 5949: debug : virNetMessageFree:72 : msg=0x7ff9caf52670 nfds=0 cb=(nil)
>2014-02-28 17:07:04.116+0000: 5949: debug : virDomainSnapshotFree:19134 : snapshot=0x7ff9caf51f70
>2014-02-28 17:07:04.116+0000: 5949: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7ff9caf51f70
>2014-02-28 17:07:04.116+0000: 5949: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7ff9caf51f70
>2014-02-28 17:07:04.116+0000: 5949: debug : virDomainSnapshotDispose:816 : release snapshot 0x7ff9caf51f70 snap1
>2014-02-28 17:07:04.116+0000: 5949: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7ff9caf514d0
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
>2014-02-28 17:07:04.116+0000: 5949: debug : virDomainFree:2310 : dom=0x7ff9caf514d0, (VM: name=devstack1, uuid=5baea458-21fd-40d6-95ac-7ef51f095e9e)
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
>2014-02-28 17:07:04.116+0000: 5949: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7ff9caf514d0
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollDispatchHandles:471 : Dispatch 1
>2014-02-28 17:07:04.116+0000: 5949: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7ff9caf514d0
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollDispatchHandles:485 : i=0 w=1
>2014-02-28 17:07:04.116+0000: 5949: debug : virDomainDispose:256 : release domain 0x7ff9caf514d0 devstack1 5baea458-21fd-40d6-95ac-7ef51f095e9e
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-02-28 17:07:04.116+0000: 5949: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7ff9caf51540
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>error: 2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>An error occurred, but the cause is unknown
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventRunDefaultImpl:303 : running default event implementation
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.116+0000: 5949: debug : virConnectUnregisterCloseCallback:19997 : conn=0x7ff9caf51540
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.116+0000: 5949: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7ff9caf51540
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
>2014-02-28 17:07:04.116+0000: 5949: debug : virConnectClose:1476 : conn=0x7ff9caf51540
>2014-02-28 17:07:04.116+0000: 5949: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7ff9caf51540
>2014-02-28 17:07:04.116+0000: 5949: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7ff9caf51540
>2014-02-28 17:07:04.116+0000: 5949: debug : virNetMessageNew:44 : msg=0x7ff9caf51970 tracked=0
>2014-02-28 17:07:04.116+0000: 5949: debug : virNetMessageEncodePayload:374 : Encode length as 28
>2014-02-28 17:07:04.116+0000: 5949: debug : virNetClientSendInternal:1952 : RPC_CLIENT_MSG_TX_QUEUE: client=0x7ff9caf524d0 len=28 prog=536903814 vers=1 proc=2 type=0 status=0 serial=7
>2014-02-28 17:07:04.116+0000: 5949: debug : virNetClientCallNew:1905 : New call 0x7ff9caf51670: msg=0x7ff9caf51970, expectReply=1, nonBlock=0
>2014-02-28 17:07:04.116+0000: 5949: debug : virNetClientIO:1714 : Outgoing message prog=536903814 version=1 serial=7 proc=2 type=0 length=28 dispatch=(nil)
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=1 d=0
>2014-02-28 17:07:04.116+0000: 5949: debug : virNetClientIO:1773 : We have the buck head=0x7ff9caf51670 call=0x7ff9caf51670
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
>2014-02-28 17:07:04.116+0000: 5949: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollCalculateTimeout:364 : No timeout is pending
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=2 timeout=-1
>2014-02-28 17:07:04.116+0000: 5949: debug : virEventPollInterruptLocked:717 : Interrupting
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollDispatchHandles:471 : Dispatch 2
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollDispatchHandles:485 : i=0 w=1
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventRunDefaultImpl:303 : running default event implementation
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=0 d=0
>2014-02-28 17:07:04.116+0000: 5951: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
>2014-02-28 17:07:04.117+0000: 5951: debug : virEventPollCalculateTimeout:364 : No timeout is pending
>2014-02-28 17:07:04.117+0000: 5951: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=1 timeout=-1
>2014-02-28 17:07:04.118+0000: 5949: debug : virNetMessageDecodeLength:149 : Got length, now need 28 total (24 more)
>2014-02-28 17:07:04.118+0000: 5949: debug : virNetClientCallDispatch:1123 : RPC_CLIENT_MSG_RX: client=0x7ff9caf524d0 len=28 prog=536903814 vers=1 proc=2 type=1 status=0 serial=7
>2014-02-28 17:07:04.118+0000: 5949: debug : virKeepAliveCheckMessage:374 : ka=0x7ff9caf527c0, client=0x7ff9caf524d0, msg=0x7ff9caf52538
>2014-02-28 17:07:04.118+0000: 5949: debug : virNetMessageClear:55 : msg=0x7ff9caf52538 nfds=0
>2014-02-28 17:07:04.118+0000: 5949: debug : virNetClientIOEventLoopPassTheBuck:1420 : Giving up the buck 0x7ff9caf51670
>2014-02-28 17:07:04.118+0000: 5949: debug : virNetClientIOEventLoopPassTheBuck:1434 : No thread to pass the buck to
>2014-02-28 17:07:04.118+0000: 5949: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1
>2014-02-28 17:07:04.118+0000: 5949: debug : virEventPollInterruptLocked:717 : Interrupting
>2014-02-28 17:07:04.118+0000: 5949: debug : virNetClientIO:1803 : All done with our call head=(nil) call=0x7ff9caf51670 rv=0
>2014-02-28 17:07:04.118+0000: 5949: debug : virNetMessageFree:72 : msg=0x7ff9caf51970 nfds=0 cb=(nil)
>2014-02-28 17:07:04.118+0000: 5949: debug : virNetClientCloseInternal:687 : client=0x7ff9caf524d0 wantclose=0
>2014-02-28 17:07:04.118+0000: 5949: debug : virNetClientMarkClose:633 : client=0x7ff9caf524d0, reason=3
>2014-02-28 17:07:04.118+0000: 5949: debug : virEventPollRemoveHandle:180 : EVENT_POLL_REMOVE_HANDLE: watch=2
>2014-02-28 17:07:04.118+0000: 5949: debug : virEventPollRemoveHandle:193 : mark delete 1 6
>2014-02-28 17:07:04.118+0000: 5949: debug : virEventPollInterruptLocked:717 : Interrupting
>2014-02-28 17:07:04.118+0000: 5949: debug : virNetClientIOEventLoopPassTheBuck:1420 : Giving up the buck (nil)
>2014-02-28 17:07:04.118+0000: 5949: debug : virNetClientIOEventLoopPassTheBuck:1434 : No thread to pass the buck to
>2014-02-28 17:07:04.118+0000: 5949: debug : virNetClientCloseLocked:646 : client=0x7ff9caf524d0, sock=0x7ff9caf52210, reason=3
>2014-02-28 17:07:04.118+0000: 5949: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7ff9caf52210
>2014-02-28 17:07:04.118+0000: 5949: debug : virObjectRef:293 : OBJECT_REF: obj=0x7ff9caf524d0
>2014-02-28 17:07:04.118+0000: 5951: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
>2014-02-28 17:07:04.118+0000: 5949: debug : virKeepAliveStop:307 : RPC_KEEPALIVE_STOP: ka=0x7ff9caf527c0 client=0x7ff9caf524d0
>2014-02-28 17:07:04.118+0000: 5951: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
>2014-02-28 17:07:04.118+0000: 5949: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7ff9caf527c0
>2014-02-28 17:07:04.118+0000: 5951: debug : virEventPollDispatchHandles:471 : Dispatch 1
>2014-02-28 17:07:04.118+0000: 5949: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7ff9caf527c0
>2014-02-28 17:07:04.118+0000: 5949: debug : virKeepAliveDispose:227 : RPC_KEEPALIVE_DISPOSE: ka=0x7ff9caf527c0
>2014-02-28 17:07:04.118+0000: 5949: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7ff9caf524d0
>2014-02-28 17:07:04.118+0000: 5949: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7ff9caf524d0
>2014-02-28 17:07:04.118+0000: 5949: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7ff9caf524d0
>2014-02-28 17:07:04.118+0000: 5949: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7ff9caf526e0
>2014-02-28 17:07:04.118+0000: 5949: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7ff9caf523f0
>2014-02-28 17:07:04.118+0000: 5949: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7ff9caf51fe0
>2014-02-28 17:07:04.118+0000: 5949: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7ff9caf516e0
>2014-02-28 17:07:04.118+0000: 5951: debug : virEventPollDispatchHandles:485 : i=0 w=1
>2014-02-28 17:07:04.118+0000: 5951: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
>2014-02-28 17:07:04.118+0000: 5951: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
>2014-02-28 17:07:04.118+0000: 5951: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
>2014-02-28 17:07:04.118+0000: 5951: debug : virEventPollCleanupHandles:565 : Cleanup 2
>2014-02-28 17:07:04.118+0000: 5951: debug : virEventPollCleanupHandles:578 : EVENT_POLL_PURGE_HANDLE: watch=2
>2014-02-28 17:07:04.118+0000: 5951: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7ff9caf524d0
>2014-02-28 17:07:04.118+0000: 5951: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7ff9caf524d0
>2014-02-28 17:07:04.118+0000: 5951: debug : virNetClientDispose:599 : RPC_CLIENT_DISPOSE: client=0x7ff9caf524d0
>2014-02-28 17:07:04.118+0000: 5951: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7ff9caf516e0
>2014-02-28 17:07:04.118+0000: 5951: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7ff9caf516e0
>2014-02-28 17:07:04.118+0000: 5951: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7ff9caf526e0
>2014-02-28 17:07:04.118+0000: 5951: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7ff9caf526e0
>2014-02-28 17:07:04.118+0000: 5951: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7ff9caf523f0
>2014-02-28 17:07:04.118+0000: 5951: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7ff9caf523f0
>2014-02-28 17:07:04.118+0000: 5951: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7ff9caf51fe0
>2014-02-28 17:07:04.118+0000: 5951: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7ff9caf51fe0
>2014-02-28 17:07:04.118+0000: 5951: debug : virFileClose:90 : Closed fd 8
>2014-02-28 17:07:04.118+0000: 5951: debug : virFileClose:90 : Closed fd 7
>2014-02-28 17:07:04.118+0000: 5951: debug : virNetMessageClear:55 : msg=0x7ff9caf52538 nfds=0
>2014-02-28 17:07:04.119+0000: 5951: debug : virObjectUnref:256 : OBJECT_UNREF: obj=0x7ff9caf52210
>2014-02-28 17:07:04.119+0000: 5951: debug : virObjectUnref:258 : OBJECT_DISPOSE: obj=0x7ff9caf52210
>2014-02-28 17:07:04.119+0000: 5951: debug : virNetSocketDispose:1020 : RPC_SOCKET_DISPOSE: sock=0x7ff9caf52210
>2014-02-28 17:07:04.119+0000: 5949: debug : virEventPollAddTimeout:225 : Used 0 timeout slots, adding at least 10 more
>2014-02-28 17:07:04.119+0000: 5949: debug : virEventPollInterruptLocked:717 : Interrupting
>2014-02-28 17:07:04.119+0000: 5951: debug : virEventPollRemoveHandle:180 : EVENT_POLL_REMOVE_HANDLE: watch=2
>2014-02-28 17:07:04.119+0000: 5949: debug : virEventPollAddTimeout:248 : EVENT_POLL_ADD_TIMEOUT: timer=1 frequency=0 cb=0x7ff9ca2af730 opaque=(nil) ff=(nil)
>2014-02-28 17:07:04.119+0000: 5951: debug : virFileClose:90 : Closed fd 6
>2014-02-28 17:07:04.119+0000: 5949: debug : virEventPollRemoveTimeout:300 : EVENT_POLL_REMOVE_TIMEOUT: timer=1
>2014-02-28 17:07:04.119+0000: 5949: debug : virEventPollInterruptLocked:713 : Skip interrupt, 0 140710552139520
>

Actions: View

Attachments on bug 1071264: 869011