[Pkg-libvirt-maintainers] Bug#706059: Bug#706059: libvirt-bin: libvirt seems unable to connect to xen using unix sockets

Michael Deegan bugs at deegan.id.au
Wed Apr 24 08:32:50 UTC 2013 

On Wed, Apr 24, 2013 at 09:25:39AM +0200, Guido Günther wrote:
> That said I'm not sure in what shape Wheezy's libvirt+xen support is
> since I'm not testing (yet) auto testing this combination at all (there
> are other xen related open bugs waiting for test and feedback). 
> 
> Please have a look at the version in experimental since this one is much
> more up to date.

Okay, I've upgraded libvirt to experimental, and there is a slight
improvement - virsh no longer complains about the connection string syntax,
and instead the error is pushed further along to where it actually gets
used:

    root at mordac:/home/michael# virsh -c xen:///var/run/xend/xend.sock
    Welcome to virsh, the virtualization interactive terminal.

    Type:  'help' for help with commands
           'quit' to quit

    virsh # list
    error: failed to connect to the hypervisor
    error: no valid connection
    error: internal error unexpected Xen URI path '/var/run/xend/xend.sock', try xen:///

    root at mordac:/home/michael# virsh -c xen:///var/run/xend/xend.sock list
    error: failed to connect to the hypervisor
    error: no valid connection
    error: internal error unexpected Xen URI path '/var/run/xend/xend.sock', try xen:///

    root at mordac:/home/michael# virsh -c ///var/run/xend/xend.sock list
    error: failed to connect to the hypervisor
    error: no valid connection
    error: no connection driver available for /var/run/xend/xend.sock
    error: Failed to reconnect to the hypervisor

One thing that leapt out of the debug logs is that experimental's libvirt
doesn't seem to be loading xen support:

wheezy:

    2013-04-24 08:02:40.627+0000: 1253: debug : virRegisterDriver:799 : registering Test as driver 0
    2013-04-24 08:02:40.628+0000: 1253: debug : virRegisterDriver:799 : registering Xen as driver 1
    2013-04-24 08:02:40.628+0000: 1253: debug : virRegisterDriver:799 : registering OPENVZ as driver 2
    2013-04-24 08:02:40.628+0000: 1253: debug : virRegisterDriver:799 : registering VMWARE as driver 3
    2013-04-24 08:02:40.629+0000: 1253: debug : virRegisterDriver:799 : registering VBOX as driver 4
    2013-04-24 08:02:40.629+0000: 1253: debug : virRegisterDriver:799 : registering remote as driver 5

experimental:

    2013-04-24 07:48:44.736+0000: 31901: debug : virRegisterDriver:768 : registering Test as driver 0
    2013-04-24 07:48:44.736+0000: 31901: debug : virRegisterDriver:768 : registering OPENVZ as driver 1
    2013-04-24 07:48:44.736+0000: 31901: debug : virRegisterDriver:768 : registering VMWARE as driver 2
    2013-04-24 07:48:44.737+0000: 31901: debug : virRegisterDriver:768 : registering VBOX as driver 3
    2013-04-24 07:48:44.737+0000: 31901: debug : virRegisterDriver:768 : registering remote as driver 4

I've attached the output of "LIBVIRT_DEBUG=1 virsh -c ///var/run/xend/xend.sock list"
for both libvirt versions for comparison.

-MD

-- 
-------------------------------------------------------------------------------
Michael Deegan                Hugaholic                http://www.deegan.id.au/
-------------------------  Jung, zr jbeel?  -----------------------------------
-------------- next part --------------
2013-04-24 08:02:32.275+0000: 1251: info : libvirt version: 0.9.12
2013-04-24 08:02:32.275+0000: 1251: debug : virInitialize:414 : register drivers
2013-04-24 08:02:32.275+0000: 1251: debug : virRegisterDriver:775 : driver=0xb771b320 name=Test
2013-04-24 08:02:32.275+0000: 1251: debug : virRegisterDriver:799 : registering Test as driver 0
2013-04-24 08:02:32.275+0000: 1251: debug : virRegisterNetworkDriver:592 : registering Test as network driver 0
2013-04-24 08:02:32.275+0000: 1251: debug : virRegisterInterfaceDriver:625 : registering Test as interface driver 0
2013-04-24 08:02:32.275+0000: 1251: debug : virRegisterStorageDriver:658 : registering Test as storage driver 0
2013-04-24 08:02:32.275+0000: 1251: debug : virRegisterDeviceMonitor:691 : registering Test as device driver 0
2013-04-24 08:02:32.275+0000: 1251: debug : virRegisterSecretDriver:724 : registering Test as secret driver 0
2013-04-24 08:02:32.275+0000: 1251: debug : virRegisterNWFilterDriver:757 : registering Test as network filter driver 0
2013-04-24 08:02:32.276+0000: 1251: debug : xenHypervisorInit:2053 : Using new hypervisor call: 40001
2013-04-24 08:02:32.276+0000: 1251: debug : xenHypervisorInit:2164 : Using hypervisor call v2, sys ver8 dom ver7
2013-04-24 08:02:32.276+0000: 1251: debug : virRegisterDriver:775 : driver=0xb771be80 name=Xen
2013-04-24 08:02:32.276+0000: 1251: debug : virRegisterDriver:799 : registering Xen as driver 1
2013-04-24 08:02:32.276+0000: 1251: debug : virRegisterDriver:775 : driver=0xb771c320 name=OPENVZ
2013-04-24 08:02:32.276+0000: 1251: debug : virRegisterDriver:799 : registering OPENVZ as driver 2
2013-04-24 08:02:32.276+0000: 1251: debug : virRegisterDriver:775 : driver=0xb771c5c0 name=VMWARE
2013-04-24 08:02:32.276+0000: 1251: debug : virRegisterDriver:799 : registering VMWARE as driver 3
2013-04-24 08:02:32.277+0000: 1251: debug : vboxRegister:137 : VBoxCGlueInit failed, using dummy driver
2013-04-24 08:02:32.277+0000: 1251: debug : virRegisterDriver:775 : driver=0xb771c860 name=VBOX
2013-04-24 08:02:32.277+0000: 1251: debug : virRegisterDriver:799 : registering VBOX as driver 4
2013-04-24 08:02:32.277+0000: 1251: debug : virRegisterNetworkDriver:592 : registering VBOX as network driver 1
2013-04-24 08:02:32.277+0000: 1251: debug : virRegisterStorageDriver:658 : registering VBOX as storage driver 1
2013-04-24 08:02:32.277+0000: 1251: debug : virRegisterDriver:775 : driver=0xb771b800 name=remote
2013-04-24 08:02:32.277+0000: 1251: debug : virRegisterDriver:799 : registering remote as driver 5
2013-04-24 08:02:32.277+0000: 1251: debug : virRegisterNetworkDriver:592 : registering remote as network driver 2
2013-04-24 08:02:32.277+0000: 1251: debug : virRegisterInterfaceDriver:625 : registering remote as interface driver 1
2013-04-24 08:02:32.277+0000: 1251: debug : virRegisterStorageDriver:658 : registering remote as storage driver 2
2013-04-24 08:02:32.277+0000: 1251: debug : virRegisterDeviceMonitor:691 : registering remote as device driver 1
2013-04-24 08:02:32.277+0000: 1251: debug : virRegisterSecretDriver:724 : registering remote as secret driver 1
2013-04-24 08:02:32.277+0000: 1251: debug : virRegisterNWFilterDriver:757 : registering remote as network filter driver 1
2013-04-24 08:02:32.277+0000: 1251: debug : virEventRegisterDefaultImpl:204 : registering default event implementation
2013-04-24 08:02:32.277+0000: 1251: debug : virEventPollAddHandle:116 : Used 0 handle slots, adding at least 10 more
2013-04-24 08:02:32.277+0000: 1251: debug : virEventPollInterruptLocked:702 : Skip interrupt, 0 0
2013-04-24 08:02:32.277+0000: 1251: debug : virEventPollAddHandle:141 : EVENT_POLL_ADD_HANDLE: watch=1 fd=4 events=1 cb=0xb74c2190 opaque=(nil) ff=(nil)
2013-04-24 08:02:32.277+0000: 1251: debug : virEventRegisterImpl:177 : addHandle=0xb74c2e10 updateHandle=0xb74c2cf0 removeHandle=0xb74c25f0 addTimeout=0xb74c2780 updateTimeout=0xb74c29e0 removeTimeout=0xb74c2b50
2013-04-24 08:02:32.278+0000: 1251: debug : virConnectOpenAuth:1455 : name=xen:///var/run/xend/xend.sock, auth=0xb771b2b8, flags=0
2013-04-24 08:02:32.278+0000: 1251: debug : virConnectGetConfigFile:1008 : Loading config file '/etc/libvirt/libvirt.conf'
2013-04-24 08:02:32.278+0000: 1252: debug : virEventRunDefaultImpl:244 : running default event implementation
2013-04-24 08:02:32.278+0000: 1252: debug : virEventPollCleanupTimeouts:506 : Cleanup 0
2013-04-24 08:02:32.278+0000: 1252: debug : virEventPollCleanupTimeouts:542 : Found 0 out of 0 timeout slots used, releasing 0
2013-04-24 08:02:32.278+0000: 1252: debug : virEventPollCleanupHandles:554 : Cleanup 1
2013-04-24 08:02:32.278+0000: 1252: debug : virEventPollMakePollFDs:383 : Prepare n=0 w=1, f=4 e=1 d=0
2013-04-24 08:02:32.278+0000: 1252: debug : virEventPollCalculateTimeout:325 : Calculate expiry of 0 timers
2013-04-24 08:02:32.278+0000: 1251: debug : do_open:1182 : name "xen:///var/run/xend/xend.sock" to URI components:
  scheme xen
  server (null)
  user (null)
  port 0
  path /var/run/xend/xend.sock

2013-04-24 08:02:32.278+0000: 1252: debug : virEventPollCalculateTimeout:351 : Timeout at 0 due in -1 ms
2013-04-24 08:02:32.278+0000: 1251: debug : do_open:1226 : trying driver 0 (Test) ...
2013-04-24 08:02:32.278+0000: 1252: debug : virEventPollRunOnce:619 : EVENT_POLL_RUN: nhandles=1 timeout=-1
2013-04-24 08:02:32.278+0000: 1251: debug : do_open:1232 : driver 0 Test returned DECLINED
2013-04-24 08:02:32.278+0000: 1251: debug : do_open:1226 : trying driver 1 (Xen) ...
2013-04-24 08:02:32.278+0000: 1251: debug : do_open:1232 : driver 1 Xen returned ERROR
2013-04-24 08:02:32.278+0000: 1251: debug : virUnrefConnect:145 : unref connection 0x8fdd9c8 1
2013-04-24 08:02:32.278+0000: 1251: debug : virReleaseConnect:94 : release connection 0x8fdd9c8
error: internal error unexpected Xen URI path '/var/run/xend/xend.sock', try xen:///
error: failed to connect to the hypervisor
2013-04-24 08:02:32.279+0000: 1251: debug : virEventPollAddTimeout:225 : Used 0 timeout slots, adding at least 10 more
2013-04-24 08:02:32.279+0000: 1251: debug : virEventPollInterruptLocked:706 : Interrupting
2013-04-24 08:02:32.279+0000: 1251: debug : virEventPollAddTimeout:248 : EVENT_POLL_ADD_TIMEOUT: timer=1 frequency=0 cb=0x8051f60 opaque=(nil) ff=(nil)
2013-04-24 08:02:32.279+0000: 1252: debug : virEventPollRunOnce:630 : Poll got 1 event(s)
2013-04-24 08:02:32.279+0000: 1252: debug : virEventPollDispatchTimeouts:415 : Dispatch 1
2013-04-24 08:02:32.279+0000: 1252: debug : virEventPollDispatchTimeouts:438 : EVENT_POLL_DISPATCH_TIMEOUT: timer=1
2013-04-24 08:02:32.279+0000: 1252: debug : virEventPollDispatchHandles:460 : Dispatch 1
2013-04-24 08:02:32.279+0000: 1252: debug : virEventPollDispatchHandles:474 : i=0 w=1
2013-04-24 08:02:32.279+0000: 1252: debug : virEventPollDispatchHandles:488 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2013-04-24 08:02:32.279+0000: 1252: debug : virEventPollCleanupTimeouts:506 : Cleanup 1
2013-04-24 08:02:32.279+0000: 1252: debug : virEventPollCleanupHandles:554 : Cleanup 1
2013-04-24 08:02:32.279+0000: 1251: debug : virEventPollRemoveTimeout:293 : EVENT_POLL_REMOVE_TIMEOUT: timer=1
2013-04-24 08:02:32.279+0000: 1251: debug : virEventPollInterruptLocked:702 : Skip interrupt, 0 -1229923472
-------------- next part --------------
2013-04-24 07:48:51.728+0000: 31903: info : libvirt version: 1.0.4
2013-04-24 07:48:51.728+0000: 31903: debug : virGlobalInit:426 : register drivers
2013-04-24 07:48:51.728+0000: 31903: debug : virRegisterDriver:756 : driver=0xb76e8720 name=Test
2013-04-24 07:48:51.728+0000: 31903: debug : virRegisterDriver:768 : registering Test as driver 0
2013-04-24 07:48:51.728+0000: 31903: debug : virRegisterNetworkDriver:603 : registering Test as network driver 0
2013-04-24 07:48:51.728+0000: 31903: debug : virRegisterInterfaceDriver:630 : registering Test as interface driver 0
2013-04-24 07:48:51.728+0000: 31903: debug : virRegisterStorageDriver:657 : registering Test as storage driver 0
2013-04-24 07:48:51.728+0000: 31903: debug : virRegisterDeviceMonitor:684 : registering Test as device driver 0
2013-04-24 07:48:51.728+0000: 31903: debug : virRegisterSecretDriver:711 : registering Test as secret driver 0
2013-04-24 07:48:51.728+0000: 31903: debug : virRegisterNWFilterDriver:738 : registering Test as network filter driver 0
2013-04-24 07:48:51.728+0000: 31903: debug : virRegisterDriver:756 : driver=0xb76e9280 name=OPENVZ
2013-04-24 07:48:51.728+0000: 31903: debug : virRegisterDriver:768 : registering OPENVZ as driver 1
2013-04-24 07:48:51.728+0000: 31903: debug : virRegisterDriver:756 : driver=0xb76e9560 name=VMWARE
2013-04-24 07:48:51.728+0000: 31903: debug : virRegisterDriver:768 : registering VMWARE as driver 2
2013-04-24 07:48:51.729+0000: 31903: debug : vboxRegister:131 : VBoxCGlueInit failed, using dummy driver
2013-04-24 07:48:51.729+0000: 31903: debug : virRegisterDriver:756 : driver=0xb76e9840 name=VBOX
2013-04-24 07:48:51.729+0000: 31903: debug : virRegisterDriver:768 : registering VBOX as driver 3
2013-04-24 07:48:51.729+0000: 31903: debug : virRegisterNetworkDriver:603 : registering VBOX as network driver 1
2013-04-24 07:48:51.729+0000: 31903: debug : virRegisterStorageDriver:657 : registering VBOX as storage driver 1
2013-04-24 07:48:51.729+0000: 31903: debug : virRegisterDriver:756 : driver=0xb76e8c40 name=remote
2013-04-24 07:48:51.729+0000: 31903: debug : virRegisterDriver:768 : registering remote as driver 4
2013-04-24 07:48:51.729+0000: 31903: debug : virRegisterNetworkDriver:603 : registering remote as network driver 2
2013-04-24 07:48:51.729+0000: 31903: debug : virRegisterInterfaceDriver:630 : registering remote as interface driver 1
2013-04-24 07:48:51.729+0000: 31903: debug : virRegisterStorageDriver:657 : registering remote as storage driver 2
2013-04-24 07:48:51.729+0000: 31903: debug : virRegisterDeviceMonitor:684 : registering remote as device driver 1
2013-04-24 07:48:51.729+0000: 31903: debug : virRegisterSecretDriver:711 : registering remote as secret driver 1
2013-04-24 07:48:51.729+0000: 31903: debug : virRegisterNWFilterDriver:738 : registering remote as network filter driver 1
2013-04-24 07:48:51.729+0000: 31903: debug : virEventRegisterDefaultImpl:230 : registering default event implementation
2013-04-24 07:48:51.729+0000: 31903: debug : virEventPollAddHandle:111 : Used 0 handle slots, adding at least 10 more
2013-04-24 07:48:51.729+0000: 31903: debug : virEventPollInterruptLocked:712 : Skip interrupt, 0 0
2013-04-24 07:48:51.729+0000: 31903: debug : virEventPollAddHandle:136 : EVENT_POLL_ADD_HANDLE: watch=1 fd=4 events=1 cb=0xb7477710 opaque=(nil) ff=(nil)
2013-04-24 07:48:51.729+0000: 31903: debug : virEventRegisterImpl:203 : addHandle=0xb7478450 updateHandle=0xb74782e0 removeHandle=0xb7477b70 addTimeout=0xb7477d20 updateTimeout=0xb7477f40 removeTimeout=0xb7478130
2013-04-24 07:48:51.729+0000: 31904: debug : virEventRunDefaultImpl:270 : running default event implementation
2013-04-24 07:48:51.729+0000: 31904: debug : virEventPollCleanupTimeouts:516 : Cleanup 0
2013-04-24 07:48:51.729+0000: 31904: debug : virEventPollCleanupTimeouts:552 : Found 0 out of 0 timeout slots used, releasing 0
2013-04-24 07:48:51.729+0000: 31903: debug : virConnectOpenAuth:1439 : name=xen:///var/run/xend/xend.sock, auth=0xb76e86b8, flags=0
2013-04-24 07:48:51.729+0000: 31904: debug : virEventPollCleanupHandles:564 : Cleanup 1
2013-04-24 07:48:51.730+0000: 31904: debug : virEventPollMakePollFDs:393 : Prepare n=0 w=1, f=4 e=1 d=0
2013-04-24 07:48:51.730+0000: 31903: debug : virObjectNew:201 : OBJECT_NEW: obj=0xb62006a0 classname=virConnect
2013-04-24 07:48:51.730+0000: 31904: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
2013-04-24 07:48:51.730+0000: 31904: debug : virEventPollCalculateTimeout:361 : Timeout at 0 due in -1 ms
2013-04-24 07:48:51.730+0000: 31903: debug : virConnectGetConfigFile:980 : Loading config file '/etc/libvirt/libvirt.conf'
2013-04-24 07:48:51.730+0000: 31904: debug : virEventPollRunOnce:629 : EVENT_POLL_RUN: nhandles=1 timeout=-1
2013-04-24 07:48:51.730+0000: 31903: debug : virConfReadFile:767 : filename=/etc/libvirt/libvirt.conf
2013-04-24 07:48:51.730+0000: 31903: debug : virFileClose:72 : Closed fd 6
2013-04-24 07:48:51.730+0000: 31903: debug : do_open:1154 : name "xen:///var/run/xend/xend.sock" to URI components:
  scheme xen
  server (null)
  user (null)
  port 0
  path /var/run/xend/xend.sock

2013-04-24 07:48:51.730+0000: 31903: debug : do_open:1200 : trying driver 0 (Test) ...
2013-04-24 07:48:51.730+0000: 31903: debug : do_open:1206 : driver 0 Test returned DECLINED
2013-04-24 07:48:51.730+0000: 31903: debug : do_open:1200 : trying driver 1 (OPENVZ) ...
2013-04-24 07:48:51.730+0000: 31903: debug : do_open:1206 : driver 1 OPENVZ returned DECLINED
2013-04-24 07:48:51.730+0000: 31903: debug : do_open:1200 : trying driver 2 (VMWARE) ...
2013-04-24 07:48:51.730+0000: 31903: debug : do_open:1206 : driver 2 VMWARE returned DECLINED
2013-04-24 07:48:51.730+0000: 31903: debug : do_open:1200 : trying driver 3 (VBOX) ...
2013-04-24 07:48:51.730+0000: 31903: debug : do_open:1206 : driver 3 VBOX returned DECLINED
2013-04-24 07:48:51.730+0000: 31903: debug : do_open:1200 : trying driver 4 (remote) ...
2013-04-24 07:48:51.730+0000: 31903: debug : doRemoteOpen:595 : proceeding with name = xen:///var/run/xend/xend.sock
2013-04-24 07:48:51.730+0000: 31903: debug : doRemoteOpen:604 : Connecting with transport 1
2013-04-24 07:48:51.730+0000: 31903: debug : doRemoteOpen:689 : Proceeding with sockname /var/run/libvirt/libvirt-sock
2013-04-24 07:48:51.731+0000: 31903: debug : virNetSocketNew:155 : localAddr=0xbf8a4654 remoteAddr=0xbf8a46d8 fd=6 errfd=-1 pid=0
2013-04-24 07:48:51.731+0000: 31903: debug : virObjectNew:201 : OBJECT_NEW: obj=0xb6200ea8 classname=virNetSocket
2013-04-24 07:48:51.731+0000: 31903: debug : virNetSocketNew:205 : RPC_SOCKET_NEW: sock=0xb6200ea8 fd=6 errfd=-1 pid=0 localAddr=127.0.0.1;0, remoteAddr=127.0.0.1;0
2013-04-24 07:48:51.731+0000: 31903: debug : virObjectNew:201 : OBJECT_NEW: obj=0xb6201100 classname=virNetClient
2013-04-24 07:48:51.731+0000: 31903: debug : virNetClientNew:326 : RPC_CLIENT_NEW: client=0xb6201100 sock=0xb6200ea8
2013-04-24 07:48:51.731+0000: 31903: debug : virObjectRef:295 : OBJECT_REF: obj=0xb6201100
2013-04-24 07:48:51.731+0000: 31903: debug : virObjectRef:295 : OBJECT_REF: obj=0xb6200ea8
2013-04-24 07:48:51.731+0000: 31903: debug : virEventPollInterruptLocked:716 : Interrupting
2013-04-24 07:48:51.731+0000: 31903: debug : virEventPollAddHandle:136 : EVENT_POLL_ADD_HANDLE: watch=2 fd=6 events=1 cb=0xb75a6960 opaque=0xb6200ea8 ff=0xb75a69d0
2013-04-24 07:48:51.731+0000: 31903: debug : virKeepAliveNew:197 : client=0xb6201100, interval=-1, count=0
2013-04-24 07:48:51.731+0000: 31903: debug : virObjectNew:201 : OBJECT_NEW: obj=0xb6201358 classname=virKeepAlive
2013-04-24 07:48:51.731+0000: 31903: debug : virKeepAliveNew:216 : RPC_KEEPALIVE_NEW: ka=0xb6201358 client=0xb6201100
2013-04-24 07:48:51.732+0000: 31903: debug : virObjectRef:295 : OBJECT_REF: obj=0xb6201100
2013-04-24 07:48:51.732+0000: 31904: debug : virEventPollRunOnce:640 : Poll got 1 event(s)
2013-04-24 07:48:51.732+0000: 31903: debug : virObjectNew:201 : OBJECT_NEW: obj=0xb6200e18 classname=virNetClientProgram
2013-04-24 07:48:51.732+0000: 31904: debug : virEventPollDispatchTimeouts:425 : Dispatch 0
2013-04-24 07:48:51.732+0000: 31903: debug : virObjectNew:201 : OBJECT_NEW: obj=0xb6201538 classname=virNetClientProgram
2013-04-24 07:48:51.732+0000: 31904: debug : virEventPollDispatchHandles:470 : Dispatch 1
2013-04-24 07:48:51.732+0000: 31903: debug : virObjectNew:201 : OBJECT_NEW: obj=0xb62015a0 classname=virNetClientProgram
2013-04-24 07:48:51.732+0000: 31904: debug : virEventPollDispatchHandles:484 : i=0 w=1
2013-04-24 07:48:51.732+0000: 31903: debug : virObjectRef:295 : OBJECT_REF: obj=0xb6200e18
2013-04-24 07:48:51.732+0000: 31904: debug : virEventPollDispatchHandles:498 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2013-04-24 07:48:51.732+0000: 31903: debug : virObjectRef:295 : OBJECT_REF: obj=0xb6201538
2013-04-24 07:48:51.732+0000: 31903: debug : virObjectRef:295 : OBJECT_REF: obj=0xb62015a0
2013-04-24 07:48:51.732+0000: 31904: debug : virEventPollCleanupTimeouts:516 : Cleanup 0
2013-04-24 07:48:51.732+0000: 31903: debug : doRemoteOpen:797 : Trying authentication
2013-04-24 07:48:51.732+0000: 31904: debug : virEventPollCleanupTimeouts:552 : Found 0 out of 0 timeout slots used, releasing 0
2013-04-24 07:48:51.732+0000: 31904: debug : virEventPollCleanupHandles:564 : Cleanup 2
2013-04-24 07:48:51.732+0000: 31903: debug : virNetMessageNew:45 : msg=0xb6200b90 tracked=0
2013-04-24 07:48:51.732+0000: 31904: debug : virEventRunDefaultImpl:270 : running default event implementation
2013-04-24 07:48:51.732+0000: 31904: debug : virEventPollCleanupTimeouts:516 : Cleanup 0
2013-04-24 07:48:51.732+0000: 31904: debug : virEventPollCleanupTimeouts:552 : Found 0 out of 0 timeout slots used, releasing 0
2013-04-24 07:48:51.732+0000: 31903: debug : virNetMessageEncodePayload:364 : Encode length as 28
2013-04-24 07:48:51.732+0000: 31904: debug : virEventPollCleanupHandles:564 : Cleanup 2
2013-04-24 07:48:51.732+0000: 31903: debug : virNetClientSendInternal:1961 : RPC_CLIENT_MSG_TX_QUEUE: client=0xb6201100 len=28 prog=536903814 vers=1 proc=66 type=0 status=0 serial=0
2013-04-24 07:48:51.732+0000: 31904: debug : virEventPollMakePollFDs:393 : Prepare n=0 w=1, f=4 e=1 d=0
2013-04-24 07:48:51.732+0000: 31904: debug : virEventPollMakePollFDs:393 : Prepare n=1 w=2, f=6 e=1 d=0
2013-04-24 07:48:51.732+0000: 31903: debug : virNetClientCallNew:1914 : New call 0xb62007f8: msg=0xb6200b90, expectReply=1, nonBlock=0
2013-04-24 07:48:51.732+0000: 31904: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
2013-04-24 07:48:51.732+0000: 31903: debug : virNetClientIO:1721 : Outgoing message prog=536903814 version=1 serial=0 proc=66 type=0 length=28 dispatch=(nil)
2013-04-24 07:48:51.732+0000: 31904: debug : virEventPollCalculateTimeout:361 : Timeout at 0 due in -1 ms
2013-04-24 07:48:51.732+0000: 31903: debug : virNetClientIO:1780 : We have the buck head=0xb62007f8 call=0xb62007f8
2013-04-24 07:48:51.732+0000: 31904: debug : virEventPollRunOnce:629 : EVENT_POLL_RUN: nhandles=2 timeout=-1
2013-04-24 07:48:51.732+0000: 31903: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0
2013-04-24 07:48:51.732+0000: 31903: debug : virEventPollInterruptLocked:716 : Interrupting
2013-04-24 07:48:51.734+0000: 31904: debug : virEventPollRunOnce:640 : Poll got 2 event(s)
2013-04-24 07:48:51.734+0000: 31904: debug : virEventPollDispatchTimeouts:425 : Dispatch 0
2013-04-24 07:48:51.734+0000: 31904: debug : virEventPollDispatchHandles:470 : Dispatch 2
2013-04-24 07:48:51.734+0000: 31904: debug : virEventPollDispatchHandles:484 : i=0 w=1
2013-04-24 07:48:51.734+0000: 31904: debug : virEventPollDispatchHandles:498 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2013-04-24 07:48:51.734+0000: 31904: debug : virEventPollCleanupTimeouts:516 : Cleanup 0
2013-04-24 07:48:51.734+0000: 31904: debug : virEventPollCleanupTimeouts:552 : Found 0 out of 0 timeout slots used, releasing 0
2013-04-24 07:48:51.734+0000: 31904: debug : virEventPollCleanupHandles:564 : Cleanup 2
2013-04-24 07:48:51.734+0000: 31904: debug : virEventRunDefaultImpl:270 : running default event implementation
2013-04-24 07:48:51.734+0000: 31904: debug : virEventPollCleanupTimeouts:516 : Cleanup 0
2013-04-24 07:48:51.735+0000: 31904: debug : virEventPollCleanupTimeouts:552 : Found 0 out of 0 timeout slots used, releasing 0
2013-04-24 07:48:51.735+0000: 31904: debug : virEventPollCleanupHandles:564 : Cleanup 2
2013-04-24 07:48:51.735+0000: 31904: debug : virEventPollMakePollFDs:393 : Prepare n=0 w=1, f=4 e=1 d=0
2013-04-24 07:48:51.735+0000: 31904: debug : virEventPollMakePollFDs:393 : Prepare n=1 w=2, f=6 e=0 d=0
2013-04-24 07:48:51.735+0000: 31904: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
2013-04-24 07:48:51.735+0000: 31904: debug : virEventPollCalculateTimeout:361 : Timeout at 0 due in -1 ms
2013-04-24 07:48:51.735+0000: 31904: debug : virEventPollRunOnce:629 : EVENT_POLL_RUN: nhandles=1 timeout=-1
2013-04-24 07:48:51.735+0000: 31903: debug : virNetMessageDecodeLength:152 : Got length, now need 36 total (32 more)
2013-04-24 07:48:51.735+0000: 31903: debug : virNetClientCallDispatch:1128 : RPC_CLIENT_MSG_RX: client=0xb6201100 len=36 prog=536903814 vers=1 proc=66 type=1 status=0 serial=0
2013-04-24 07:48:51.735+0000: 31903: debug : virKeepAliveCheckMessage:375 : ka=0xb6201358, client=0xb6201100, msg=0xb620113c
2013-04-24 07:48:51.735+0000: 31903: debug : virNetMessageClear:56 : msg=0xb620113c nfds=0
2013-04-24 07:48:51.735+0000: 31903: debug : virNetClientIOEventLoopPassTheBuck:1427 : Giving up the buck 0xb62007f8
2013-04-24 07:48:51.735+0000: 31903: debug : virNetClientIOEventLoopPassTheBuck:1441 : No thread to pass the buck to
2013-04-24 07:48:51.735+0000: 31903: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1
2013-04-24 07:48:51.735+0000: 31903: debug : virEventPollInterruptLocked:716 : Interrupting
2013-04-24 07:48:51.735+0000: 31903: debug : virNetClientIO:1810 : All done with our call head=(nil) call=0xb62007f8 rv=0
2013-04-24 07:48:51.735+0000: 31903: debug : virNetMessageFree:73 : msg=0xb6200b90 nfds=0 cb=(nil)
2013-04-24 07:48:51.735+0000: 31903: debug : virNetMessageNew:45 : msg=0xb6200d98 tracked=0
2013-04-24 07:48:51.735+0000: 31903: debug : virNetMessageEncodePayload:364 : Encode length as 32
2013-04-24 07:48:51.735+0000: 31903: debug : virNetClientSendInternal:1961 : RPC_CLIENT_MSG_TX_QUEUE: client=0xb6201100 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=1
2013-04-24 07:48:51.735+0000: 31903: debug : virNetClientCallNew:1914 : New call 0xb62007f8: msg=0xb6200d98, expectReply=1, nonBlock=0
2013-04-24 07:48:51.735+0000: 31903: debug : virNetClientIO:1721 : Outgoing message prog=536903814 version=1 serial=1 proc=60 type=0 length=32 dispatch=(nil)
2013-04-24 07:48:51.735+0000: 31903: debug : virNetClientIO:1780 : We have the buck head=0xb62007f8 call=0xb62007f8
2013-04-24 07:48:51.735+0000: 31903: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0
2013-04-24 07:48:51.735+0000: 31903: debug : virEventPollInterruptLocked:716 : Interrupting
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollRunOnce:640 : Poll got 1 event(s)
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollDispatchTimeouts:425 : Dispatch 0
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollDispatchHandles:470 : Dispatch 1
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollDispatchHandles:484 : i=0 w=1
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollDispatchHandles:498 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollCleanupTimeouts:516 : Cleanup 0
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollCleanupTimeouts:552 : Found 0 out of 0 timeout slots used, releasing 0
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollCleanupHandles:564 : Cleanup 2
2013-04-24 07:48:51.736+0000: 31904: debug : virEventRunDefaultImpl:270 : running default event implementation
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollCleanupTimeouts:516 : Cleanup 0
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollCleanupTimeouts:552 : Found 0 out of 0 timeout slots used, releasing 0
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollCleanupHandles:564 : Cleanup 2
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollMakePollFDs:393 : Prepare n=0 w=1, f=4 e=1 d=0
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollMakePollFDs:393 : Prepare n=1 w=2, f=6 e=0 d=0
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollCalculateTimeout:361 : Timeout at 0 due in -1 ms
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollRunOnce:629 : EVENT_POLL_RUN: nhandles=1 timeout=-1
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollRunOnce:640 : Poll got 1 event(s)
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollDispatchTimeouts:425 : Dispatch 0
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollDispatchHandles:470 : Dispatch 1
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollDispatchHandles:484 : i=0 w=1
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollDispatchHandles:498 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollCleanupTimeouts:516 : Cleanup 0
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollCleanupTimeouts:552 : Found 0 out of 0 timeout slots used, releasing 0
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollCleanupHandles:564 : Cleanup 2
2013-04-24 07:48:51.736+0000: 31904: debug : virEventRunDefaultImpl:270 : running default event implementation
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollCleanupTimeouts:516 : Cleanup 0
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollCleanupTimeouts:552 : Found 0 out of 0 timeout slots used, releasing 0
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollCleanupHandles:564 : Cleanup 2
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollMakePollFDs:393 : Prepare n=0 w=1, f=4 e=1 d=0
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollMakePollFDs:393 : Prepare n=1 w=2, f=6 e=0 d=0
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollCalculateTimeout:361 : Timeout at 0 due in -1 ms
2013-04-24 07:48:51.736+0000: 31904: debug : virEventPollRunOnce:629 : EVENT_POLL_RUN: nhandles=1 timeout=-1
2013-04-24 07:48:51.737+0000: 31903: debug : virNetMessageDecodeLength:152 : Got length, now need 32 total (28 more)
2013-04-24 07:48:51.737+0000: 31903: debug : virNetClientCallDispatch:1128 : RPC_CLIENT_MSG_RX: client=0xb6201100 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=1
2013-04-24 07:48:51.737+0000: 31903: debug : virKeepAliveCheckMessage:375 : ka=0xb6201358, client=0xb6201100, msg=0xb620113c
2013-04-24 07:48:51.737+0000: 31903: debug : virNetMessageClear:56 : msg=0xb620113c nfds=0
2013-04-24 07:48:51.737+0000: 31903: debug : virNetClientIOEventLoopPassTheBuck:1427 : Giving up the buck 0xb62007f8
2013-04-24 07:48:51.737+0000: 31903: debug : virNetClientIOEventLoopPassTheBuck:1441 : No thread to pass the buck to
2013-04-24 07:48:51.737+0000: 31903: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1
2013-04-24 07:48:51.737+0000: 31903: debug : virEventPollInterruptLocked:716 : Interrupting
2013-04-24 07:48:51.737+0000: 31903: debug : virNetClientIO:1810 : All done with our call head=(nil) call=0xb62007f8 rv=0
2013-04-24 07:48:51.737+0000: 31903: debug : virNetMessageFree:73 : msg=0xb6200d98 nfds=0 cb=(nil)
2013-04-24 07:48:51.737+0000: 31903: debug : doRemoteOpen:823 : Trying to open URI xen:///var/run/xend/xend.sock
2013-04-24 07:48:51.737+0000: 31903: debug : virNetMessageNew:45 : msg=0xb62008b8 tracked=0
2013-04-24 07:48:51.737+0000: 31903: debug : virNetMessageEncodePayload:364 : Encode length as 72
2013-04-24 07:48:51.737+0000: 31903: debug : virNetClientSendInternal:1961 : RPC_CLIENT_MSG_TX_QUEUE: client=0xb6201100 len=72 prog=536903814 vers=1 proc=1 type=0 status=0 serial=2
2013-04-24 07:48:51.738+0000: 31903: debug : virNetClientCallNew:1914 : New call 0xb62007f8: msg=0xb62008b8, expectReply=1, nonBlock=0
2013-04-24 07:48:51.738+0000: 31903: debug : virNetClientIO:1721 : Outgoing message prog=536903814 version=1 serial=2 proc=1 type=0 length=72 dispatch=(nil)
2013-04-24 07:48:51.738+0000: 31903: debug : virNetClientIO:1780 : We have the buck head=0xb62007f8 call=0xb62007f8
2013-04-24 07:48:51.738+0000: 31903: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0
2013-04-24 07:48:51.738+0000: 31903: debug : virEventPollInterruptLocked:716 : Interrupting
2013-04-24 07:48:51.739+0000: 31904: debug : virEventPollRunOnce:640 : Poll got 1 event(s)
2013-04-24 07:48:51.739+0000: 31904: debug : virEventPollDispatchTimeouts:425 : Dispatch 0
2013-04-24 07:48:51.739+0000: 31904: debug : virEventPollDispatchHandles:470 : Dispatch 1
2013-04-24 07:48:51.740+0000: 31904: debug : virEventPollDispatchHandles:484 : i=0 w=1
2013-04-24 07:48:51.740+0000: 31904: debug : virEventPollDispatchHandles:498 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2013-04-24 07:48:51.740+0000: 31903: debug : virNetMessageDecodeLength:152 : Got length, now need 248 total (244 more)
2013-04-24 07:48:51.740+0000: 31903: debug : virNetClientCallDispatch:1128 : RPC_CLIENT_MSG_RX: client=0xb6201100 len=248 prog=536903814 vers=1 proc=1 type=1 status=1 serial=2
2013-04-24 07:48:51.740+0000: 31904: debug : virEventPollCleanupTimeouts:516 : Cleanup 0
2013-04-24 07:48:51.740+0000: 31903: debug : virKeepAliveCheckMessage:375 : ka=0xb6201358, client=0xb6201100, msg=0xb620113c
2013-04-24 07:48:51.740+0000: 31904: debug : virEventPollCleanupTimeouts:552 : Found 0 out of 0 timeout slots used, releasing 0
2013-04-24 07:48:51.740+0000: 31903: debug : virNetMessageClear:56 : msg=0xb620113c nfds=0
2013-04-24 07:48:51.740+0000: 31904: debug : virEventPollCleanupHandles:564 : Cleanup 2
2013-04-24 07:48:51.740+0000: 31903: debug : virNetClientIOEventLoopPassTheBuck:1427 : Giving up the buck 0xb62007f8
2013-04-24 07:48:51.740+0000: 31904: debug : virEventRunDefaultImpl:270 : running default event implementation
2013-04-24 07:48:51.740+0000: 31903: debug : virNetClientIOEventLoopPassTheBuck:1441 : No thread to pass the buck to
2013-04-24 07:48:51.740+0000: 31904: debug : virEventPollCleanupTimeouts:516 : Cleanup 0
2013-04-24 07:48:51.740+0000: 31903: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1
2013-04-24 07:48:51.740+0000: 31904: debug : virEventPollCleanupTimeouts:552 : Found 0 out of 0 timeout slots used, releasing 0
2013-04-24 07:48:51.740+0000: 31904: debug : virEventPollCleanupHandles:564 : Cleanup 2
2013-04-24 07:48:51.740+0000: 31904: debug : virEventPollMakePollFDs:393 : Prepare n=0 w=1, f=4 e=1 d=0
2013-04-24 07:48:51.740+0000: 31904: debug : virEventPollMakePollFDs:393 : Prepare n=1 w=2, f=6 e=0 d=0
2013-04-24 07:48:51.740+0000: 31904: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
2013-04-24 07:48:51.740+0000: 31904: debug : virEventPollCalculateTimeout:361 : Timeout at 0 due in -1 ms
2013-04-24 07:48:51.740+0000: 31904: debug : virEventPollRunOnce:629 : EVENT_POLL_RUN: nhandles=1 timeout=-1
2013-04-24 07:48:51.740+0000: 31903: debug : virEventPollInterruptLocked:716 : Interrupting
2013-04-24 07:48:51.740+0000: 31904: debug : virEventPollRunOnce:640 : Poll got 1 event(s)
2013-04-24 07:48:51.740+0000: 31904: debug : virEventPollDispatchTimeouts:425 : Dispatch 0
2013-04-24 07:48:51.740+0000: 31903: debug : virNetClientIO:1810 : All done with our call head=(nil) call=0xb62007f8 rv=0
2013-04-24 07:48:51.740+0000: 31904: debug : virEventPollDispatchHandles:470 : Dispatch 1
2013-04-24 07:48:51.740+0000: 31904: debug : virEventPollDispatchHandles:484 : i=0 w=1
2013-04-24 07:48:51.740+0000: 31904: debug : virEventPollDispatchHandles:498 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2013-04-24 07:48:51.740+0000: 31903: debug : virNetMessageFree:73 : msg=0xb62008b8 nfds=0 cb=(nil)
2013-04-24 07:48:51.740+0000: 31904: debug : virEventPollCleanupTimeouts:516 : Cleanup 0
2013-04-24 07:48:51.740+0000: 31903: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0xb6200e18
2013-04-24 07:48:51.740+0000: 31904: debug : virEventPollCleanupTimeouts:552 : Found 0 out of 0 timeout slots used, releasing 0
2013-04-24 07:48:51.740+0000: 31903: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0xb6201538
2013-04-24 07:48:51.740+0000: 31904: debug : virEventPollCleanupHandles:564 : Cleanup 2
2013-04-24 07:48:51.740+0000: 31903: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0xb62015a0
2013-04-24 07:48:51.741+0000: 31904: debug : virEventRunDefaultImpl:270 : running default event implementation
2013-04-24 07:48:51.741+0000: 31903: debug : virNetClientCloseInternal:688 : client=0xb6201100 wantclose=0
2013-04-24 07:48:51.741+0000: 31904: debug : virEventPollCleanupTimeouts:516 : Cleanup 0
2013-04-24 07:48:51.741+0000: 31903: debug : virNetClientMarkClose:634 : client=0xb6201100, reason=3
2013-04-24 07:48:51.741+0000: 31904: debug : virEventPollCleanupTimeouts:552 : Found 0 out of 0 timeout slots used, releasing 0
2013-04-24 07:48:51.741+0000: 31903: debug : virEventPollRemoveHandle:180 : EVENT_POLL_REMOVE_HANDLE: watch=2
2013-04-24 07:48:51.741+0000: 31904: debug : virEventPollCleanupHandles:564 : Cleanup 2
2013-04-24 07:48:51.741+0000: 31904: debug : virEventPollMakePollFDs:393 : Prepare n=0 w=1, f=4 e=1 d=0
2013-04-24 07:48:51.741+0000: 31904: debug : virEventPollMakePollFDs:393 : Prepare n=1 w=2, f=6 e=1 d=0
2013-04-24 07:48:51.741+0000: 31904: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
2013-04-24 07:48:51.741+0000: 31904: debug : virEventPollCalculateTimeout:361 : Timeout at 0 due in -1 ms
2013-04-24 07:48:51.741+0000: 31904: debug : virEventPollRunOnce:629 : EVENT_POLL_RUN: nhandles=2 timeout=-1
2013-04-24 07:48:51.741+0000: 31903: debug : virEventPollRemoveHandle:193 : mark delete 1 6
2013-04-24 07:48:51.741+0000: 31904: debug : virEventPollRunOnce:640 : Poll got 1 event(s)
2013-04-24 07:48:51.741+0000: 31903: debug : virEventPollInterruptLocked:716 : Interrupting
2013-04-24 07:48:51.741+0000: 31903: debug : virNetClientIOEventLoopPassTheBuck:1427 : Giving up the buck (nil)
2013-04-24 07:48:51.741+0000: 31904: debug : virEventPollDispatchTimeouts:425 : Dispatch 0
2013-04-24 07:48:51.741+0000: 31903: debug : virNetClientIOEventLoopPassTheBuck:1441 : No thread to pass the buck to
2013-04-24 07:48:51.741+0000: 31904: debug : virEventPollDispatchHandles:470 : Dispatch 2
2013-04-24 07:48:51.741+0000: 31903: debug : virNetClientCloseLocked:647 : client=0xb6201100, sock=0xb6200ea8, reason=3
2013-04-24 07:48:51.741+0000: 31904: debug : virEventPollDispatchHandles:484 : i=0 w=1
2013-04-24 07:48:51.741+0000: 31903: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0xb6200ea8
2013-04-24 07:48:51.741+0000: 31904: debug : virEventPollDispatchHandles:498 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2013-04-24 07:48:51.741+0000: 31903: debug : virObjectRef:295 : OBJECT_REF: obj=0xb6201100
2013-04-24 07:48:51.741+0000: 31903: debug : virKeepAliveStop:308 : RPC_KEEPALIVE_STOP: ka=0xb6201358 client=0xb6201100
2013-04-24 07:48:51.741+0000: 31904: debug : virEventPollDispatchHandles:484 : i=1 w=2
2013-04-24 07:48:51.741+0000: 31903: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0xb6201358
2013-04-24 07:48:51.741+0000: 31904: debug : virEventPollDispatchHandles:487 : Skip deleted n=1 w=2 f=6
2013-04-24 07:48:51.741+0000: 31903: debug : virObjectUnref:260 : OBJECT_DISPOSE: obj=0xb6201358
2013-04-24 07:48:51.741+0000: 31904: debug : virEventPollCleanupTimeouts:516 : Cleanup 0
2013-04-24 07:48:51.741+0000: 31903: debug : virKeepAliveDispose:228 : RPC_KEEPALIVE_DISPOSE: ka=0xb6201358
2013-04-24 07:48:51.741+0000: 31904: debug : virEventPollCleanupTimeouts:552 : Found 0 out of 0 timeout slots used, releasing 0
2013-04-24 07:48:51.741+0000: 31903: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0xb6201100
2013-04-24 07:48:51.741+0000: 31904: debug : virEventPollCleanupHandles:564 : Cleanup 2
2013-04-24 07:48:51.741+0000: 31903: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0xb6201100
2013-04-24 07:48:51.741+0000: 31904: debug : virEventPollCleanupHandles:577 : EVENT_POLL_PURGE_HANDLE: watch=2
2013-04-24 07:48:51.742+0000: 31903: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0xb6201100
2013-04-24 07:48:51.742+0000: 31904: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0xb6201100
2013-04-24 07:48:51.742+0000: 31903: debug : do_open:1206 : driver 4 remote returned ERROR
2013-04-24 07:48:51.742+0000: 31904: debug : virObjectUnref:260 : OBJECT_DISPOSE: obj=0xb6201100
2013-04-24 07:48:51.742+0000: 31903: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0xb62006a0
2013-04-24 07:48:51.742+0000: 31904: debug : virNetClientDispose:600 : RPC_CLIENT_DISPOSE: client=0xb6201100
2013-04-24 07:48:51.742+0000: 31903: debug : virObjectUnref:260 : OBJECT_DISPOSE: obj=0xb62006a0
2013-04-24 07:48:51.742+0000: 31904: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0xb6200e18
2013-04-24 07:48:51.742+0000: 31904: debug : virObjectUnref:260 : OBJECT_DISPOSE: obj=0xb6200e18
2013-04-24 07:48:51.742+0000: 31904: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0xb6201538
2013-04-24 07:48:51.742+0000: 31904: debug : virObjectUnref:260 : OBJECT_DISPOSE: obj=0xb6201538
2013-04-24 07:48:51.742+0000: 31904: debug : virObjectUnref:258 : OBJECT_UNREF: obj=0xb62015a0
2013-04-24 07:48:51.742+0000: 31904: debug : virObjectUnref:260 : OBJECT_DISPOSE: obj=0xb62015a0
2013-04-24 07:48:51.742+0000: 31904: debug : virFileClose:72 : Closed fd 8
failed to connect to the hypervisor
error: 2013-04-24 07:48:51.error: 2013-04-24 07:48:51.742+0000: 31904: debug : virObjectUnref:260 : OBJECT_DISPOSE: obj=0xb6200ea8
2013-04-24 07:48:51.742+0000: 31904: debug : virNetSocketDispose:1004 : RPC_SOCKET_DISPOSE: sock=0xb6200ea8
2013-04-24 07:48:51.742+0000: 31904: debug : virEventPollRemoveHandle:180 : EVENT_POLL_REMOVE_HANDLE: watch=2
2013-04-24 07:48:51.742+0000: 31903: debug : virEventPollAddTimeout:225 : Used 0 timeout slots, adding at least 10 more
2013-04-24 07:48:51.742+0000: 31903: debug : virEventPollInterruptLocked:716 : Interrupting
2013-04-24 07:48:51.742+0000: 31903: debug : virEventPollAddTimeout:248 : EVENT_POLL_ADD_TIMEOUT: timer=1 frequency=0 cb=0x80534a0 opaque=(nil) ff=(nil)
2013-04-24 07:48:51.743+0000: 31904: debug : virFileClose:72 : Closed fd 6
2013-04-24 07:48:51.743+0000: 31903: debug : virEventPollRemoveTimeout:300 : EVENT_POLL_REMOVE_TIMEOUT: timer=1
2013-04-24 07:48:51.743+0000: 31903: debug : virEventPollInterruptLocked:712 : Skip interrupt, 0 -1229239440

More information about the Pkg-libvirt-maintainers mailing list