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@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@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@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? -----------------------------------
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
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