Gentoo Forums
Gentoo Forums
Gentoo Forums
Quick Search: in
Libvirtd crashed on start
View unanswered posts
View posts from last 24 hours

 
Reply to topic    Gentoo Forums Forum Index Unsupported Software
View previous topic :: View next topic  
Author Message
betlor5
n00b
n00b


Joined: 12 Jul 2013
Posts: 23

PostPosted: Thu Oct 24, 2013 6:28 pm    Post subject: Libvirtd crashed on start Reply with quote

Hi,

if I try to start libvirtd with the init.d it will crash, but it doesn't generate any error massages during the start:
Code:

/etc/init.d/libvirtd start
 * Starting libvirtd ...                                                                                     [ ok ]

If I run the status command shortly after:
Code:

/etc/init.d/libvirtd status
 * status: crashed


this happens independ from which use-flag variables I have set. At the moment I am using:
Quote:

app-emulation/libvirt-1.1.3 USE="caps libvirtd macvtap nls python qemu udev vepa virt-network -audit -avahi -firewalld -fuse* -iscsi -lvm -lxc -nfs* -numa* -openvz -parted -pcap* -phyp -policykit* -rbd -sasl* (-selinux) -systemd -uml* -virtualbox -xen" PYTHON_SINGLE_TARGET="python2_7 -python2_6" PYTHON_TARGETS="python2_7 -python2_6"


Right now I also installed qemu and kvm, which I intend to use together with libvirt. I hope you can help me.
Back to top
View user's profile Send private message
betlor5
n00b
n00b


Joined: 12 Jul 2013
Posts: 23

PostPosted: Fri Oct 25, 2013 10:39 am    Post subject: Reply with quote

Hi,

so I had a look at http://fedoraproject.org/wiki/How_to_debug_Virtualization_problems and tried the commands
Code:

virt-host-validate
  QEMU: Checking for hardware virtualization                                 : PASS
  QEMU: Checking for device /dev/kvm                                         : PASS
  QEMU: Checking for device /dev/vhost-net                                   : PASS
  QEMU: Checking for device /dev/net/tun                                     : PASS

LIBVIRT_DEBUG=1 virsh list --all
2013-10-25 10:34:11.681+0000: 18580: info : libvirt version: 1.1.3
2013-10-25 10:34:11.681+0000: 18580: debug : virGlobalInit:438 : register driver                                   s
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterDriver:764 : driver=0x7f                                   a23d8426e0 name=Test
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterDriver:776 : registering                                    Test as driver 0
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterNetworkDriver:611 : regi                                   stering Test as network driver 0
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterInterfaceDriver:638 : re                                   gistering Test as interface driver 0
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterStorageDriver:665 : regi                                   stering Test as storage driver 0
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterNodeDeviceDriver:692 : r                                   egistering Test as device driver 0
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterSecretDriver:719 : regis                                   tering Test as secret driver 0
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterNWFilterDriver:746 : reg                                   istering Test as network filter driver 0
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterDriver:764 : driver=0x7f                                   a23d843e00 name=VMWARE
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterDriver:776 : registering                                    VMWARE as driver 1
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterDriver:764 : driver=0x7f                                   a23d844420 name=ESX
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterDriver:776 : registering                                    ESX as driver 2
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterInterfaceDriver:638 : re                                   gistering ESX as interface driver 1
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterNetworkDriver:611 : regi                                   stering ESX as network driver 1
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterStorageDriver:665 : regi                                   stering ESX as storage driver 1
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterNodeDeviceDriver:692 : r                                   egistering ESX as device driver 1
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterSecretDriver:719 : regis                                   tering ESX as secret driver 1
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterNWFilterDriver:746 : reg                                   istering ESX as network filter driver 1
2013-10-25 10:34:11.681+0000: 18580: debug : parallelsRegister:2423 : Can't find                                    prlctl command in the PATH env
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterDriver:764 : driver=0x7f                                   a23d843120 name=remote
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterDriver:776 : registering                                    remote as driver 3
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterNetworkDriver:611 : regi                                   stering remote as network driver 2
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterInterfaceDriver:638 : re                                   gistering remote as interface driver 2
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterStorageDriver:665 : regi                                   stering remote as storage driver 2
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterNodeDeviceDriver:692 : r                                   egistering remote as device driver 2
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterSecretDriver:719 : regis                                   tering remote as secret driver 2
2013-10-25 10:34:11.681+0000: 18580: debug : virRegisterNWFilterDriver:746 : reg                                   istering remote as network filter driver 2
2013-10-25 10:34:11.681+0000: 18580: debug : virEventRegisterDefaultImpl:231 : r                                   egistering default event implementation
2013-10-25 10:34:11.681+0000: 18580: debug : virEventPollAddHandle:111 : Used 0                                    handle slots, adding at least 10 more
2013-10-25 10:34:11.681+0000: 18580: debug : virEventPollInterruptLocked:713 : S                                   kip interrupt, 0 0
2013-10-25 10:34:11.681+0000: 18580: debug : virEventPollAddHandle:136 : EVENT_P                                   OLL_ADD_HANDLE: watch=1 fd=4 events=1 cb=0x7fa23d397a00 opaque=(nil) ff=(nil)
2013-10-25 10:34:11.681+0000: 18580: debug : virEventRegisterImpl:204 : addHandl                                   e=0x7fa23d3986e0 updateHandle=0x7fa23d398580 removeHandle=0x7fa23d397e80 addTime                                   out=0x7fa23d398000 updateTimeout=0x7fa23d398200 removeTimeout=0x7fa23d3983a0
2013-10-25 10:34:11.681+0000: 18580: debug : virConnectOpenAuth:1459 : name=(nul                                   l), auth=0x7fa23d842640, flags=0
2013-10-25 10:34:11.681+0000: 18580: debug : virObjectNew:199 : OBJECT_NEW: obj=                                   0x7fa23e45f6b0 classname=virConnect
2013-10-25 10:34:11.681+0000: 18580: debug : virObjectNew:199 : OBJECT_NEW: obj=                                   0x7fa23e45f320 classname=virConnectCloseCallbackData
2013-10-25 10:34:11.681+0000: 18581: debug : virEventRunDefaultImpl:271 : runnin                                   g default event implementation
2013-10-25 10:34:11.681+0000: 18581: debug : virEventPollCleanupTimeouts:517 : C                                   leanup 0
2013-10-25 10:34:11.681+0000: 18580: debug : virConnectGetConfigFile:1000 : Load                                   ing config file '/etc/libvirt/libvirt.conf'
2013-10-25 10:34:11.681+0000: 18580: debug : virConfReadFile:748 : filename=/etc                                   /libvirt/libvirt.conf
2013-10-25 10:34:11.681+0000: 18581: debug : virEventPollCleanupTimeouts:553 : F                                   ound 0 out of 0 timeout slots used, releasing 0
2013-10-25 10:34:11.681+0000: 18581: debug : virEventPollCleanupHandles:565 : Cl                                   eanup 1
2013-10-25 10:34:11.681+0000: 18580: debug : virFileClose:90 : Closed fd 6
2013-10-25 10:34:11.681+0000: 18581: debug : virEventPollMakePollFDs:394 : Prepa                                   re n=0 w=1, f=4 e=1 d=0
2013-10-25 10:34:11.681+0000: 18581: debug : virEventPollCalculateTimeout:332 :                                    Calculate expiry of 0 timers
2013-10-25 10:34:11.681+0000: 18581: debug : virEventPollCalculateTimeout:364 :                                    No timeout is pending
2013-10-25 10:34:11.681+0000: 18580: debug : do_open:1175 : no name, allowing dr                                   iver auto-select
2013-10-25 10:34:11.681+0000: 18581: debug : virEventPollRunOnce:630 : EVENT_POL                                   L_RUN: nhandles=1 timeout=-1
2013-10-25 10:34:11.681+0000: 18580: debug : do_open:1217 : trying driver 0 (Tes                                   t) ...
2013-10-25 10:34:11.681+0000: 18580: debug : do_open:1224 : driver 0 Test return                                   ed DECLINED
2013-10-25 10:34:11.681+0000: 18580: debug : do_open:1217 : trying driver 1 (VMW                                   ARE) ...
2013-10-25 10:34:11.681+0000: 18580: debug : do_open:1224 : driver 1 VMWARE retu                                   rned DECLINED
2013-10-25 10:34:11.681+0000: 18580: debug : do_open:1217 : trying driver 2 (ESX                                   ) ...
2013-10-25 10:34:11.681+0000: 18580: debug : do_open:1224 : driver 2 ESX returne                                   d DECLINED
2013-10-25 10:34:11.681+0000: 18580: debug : do_open:1217 : trying driver 3 (rem                                   ote) ...
2013-10-25 10:34:11.681+0000: 18580: debug : remoteConnectOpen:998 : Auto-probe                                    remote URI
2013-10-25 10:34:11.681+0000: 18580: debug : doRemoteOpen:599 : proceeding with                                    name =
2013-10-25 10:34:11.682+0000: 18580: debug : doRemoteOpen:608 : Connecting with                                    transport 1
2013-10-25 10:34:11.682+0000: 18580: debug : doRemoteOpen:699 : Proceeding with                                    sockname /var/run/libvirt/libvirt-sock
2013-10-25 10:34:11.682+0000: 18580: debug : virFileClose:90 : Closed fd 6
2013-10-25 10:34:11.682+0000: 18580: debug : virNetClientCloseInternal:687 : cli                                   ent=(nil) wantclose=0
2013-10-25 10:34:11.682+0000: 18580: debug : do_open:1224 : driver 3 remote retu                                   rned ERROR
2013-10-25 10:34:11.682+0000: 18580: debug : virObjectUnref:256 : OBJECT_UNREF:                                    obj=0x7fa23e45f6b0
2013-10-25 10:34:11.682+0000: 18580: debug : virObjectUnref:258 : OBJECT_DISPOSE                                   : obj=0x7fa23e45f6b0
2013-10-25 10:34:11.682+0000: 18580: debug : virObjectUnref:256 : OBJECT_UNREF:                                    obj=0x7fa23e45f320
2013-10-25 10:34:11.682+0000: 18580: debug : virObjectUnref:258 : OBJECT_DISPOSE                                   : obj=0x7fa23e45f320
error: failed to connect to the hypervisor
error: no valid connection
error: Failed to connect socket to '/var/run/libvirt/libvirt-sock': No such file                                    or directory

2013-10-25 10:34:11.682+0000: 18580: debug : virEventPollAddTimeout:225 : Used 0                                    timeout slots, adding at least 10 more
2013-10-25 10:34:11.682+0000: 18580: debug : virEventPollInterruptLocked:717 : I                                   nterrupting
2013-10-25 10:34:11.682+0000: 18581: debug : virEventPollRunOnce:641 : Poll got                                    1 event(s)
2013-10-25 10:34:11.682+0000: 18580: debug : virEventPollAddTimeout:248 : EVENT_                                   POLL_ADD_TIMEOUT: timer=1 frequency=0 cb=0x7fa23da8cbf0 opaque=(nil) ff=(nil)
2013-10-25 10:34:11.682+0000: 18581: debug : virEventPollDispatchTimeouts:426 :                                    Dispatch 1
2013-10-25 10:34:11.682+0000: 18581: debug : virEventPollDispatchTimeouts:449 :                                    EVENT_POLL_DISPATCH_TIMEOUT: timer=1
2013-10-25 10:34:11.682+0000: 18581: debug : virEventPollDispatchHandles:471 : D                                   ispatch 1
2013-10-25 10:34:11.682+0000: 18581: debug : virEventPollDispatchHandles:485 : i                                   =0 w=1
2013-10-25 10:34:11.682+0000: 18581: debug : virEventPollDispatchHandles:499 : E                                   VENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2013-10-25 10:34:11.682+0000: 18581: debug : virEventPollCleanupTimeouts:517 : C                                   leanup 1
2013-10-25 10:34:11.682+0000: 18581: debug : virEventPollCleanupHandles:565 : Cl                                   eanup 1
2013-10-25 10:34:11.682+0000: 18580: debug : virEventPollRemoveTimeout:300 : EVE                                   NT_POLL_REMOVE_TIMEOUT: timer=1
2013-10-25 10:34:11.682+0000: 18580: debug : virEventPollInterruptLocked:713 : S                                   kip interrupt, 0 140334721341184

as root. If I try the same commands as a user I get
[code:1:da4721b047]
virt-host-validate
QEMU: Checking for hardware virtualization : PASS
QEMU: Checking for device /dev/kvm : FAIL (Check that the 'kvm-intel' or 'kvm-amd' modules are loaded & the BIOS has enabled virtualization)
QEMU: Checking for device /dev/vhost-net : WARN (Load the 'vhost_net' module to improve performance of virtio networking)
QEMU: Checking for device /dev/net/tun : PASS

LIBVIRT_DEBUG=1 virsh list --all
2013-10-25 10:37:04.932+0000: 18585: info : libvirt version: 1.1.3
2013-10-25 10:37:04.932+0000: 18585: debug : virGlobalInit:438 : register drivers
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterDriver:764 : driver=0x7f048118c6e0 name=Test
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterDriver:776 : registering Test as driver 0
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterNetworkDriver:611 : registering Test as network driver 0
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterInterfaceDriver:638 : registering Test as interface driver 0
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterStorageDriver:665 : registering Test as storage driver 0
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterNodeDeviceDriver:692 : registering Test as device driver 0
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterSecretDriver:719 : registering Test as secret driver 0
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterNWFilterDriver:746 : registering Test as network filter driver 0
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterDriver:764 : driver=0x7f048118de00 name=VMWARE
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterDriver:776 : registering VMWARE as driver 1
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterDriver:764 : driver=0x7f048118e420 name=ESX
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterDriver:776 : registering ESX as driver 2
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterInterfaceDriver:638 : registering ESX as interface driver 1
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterNetworkDriver:611 : registering ESX as network driver 1
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterStorageDriver:665 : registering ESX as storage driver 1
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterNodeDeviceDriver:692 : registering ESX as device driver 1
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterSecretDriver:719 : registering ESX as secret driver 1
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterNWFilterDriver:746 : registering ESX as network filter driver 1
2013-10-25 10:37:04.932+0000: 18585: debug : parallelsRegister:2423 : Can't find prlctl command in the PATH env
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterDriver:764 : driver=0x7f048118d120 name=remote
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterDriver:776 : registering remote as driver 3
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterNetworkDriver:611 : registering remote as network driver 2
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterInterfaceDriver:638 : registering remote as interface driver 2
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterStorageDriver:665 : registering remote as storage driver 2
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterNodeDeviceDriver:692 : registering remote as device driver 2
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterSecretDriver:719 : registering remote as secret driver 2
2013-10-25 10:37:04.932+0000: 18585: debug : virRegisterNWFilterDriver:746 : registering remote as network filter driver 2
2013-10-25 10:37:04.932+0000: 18585: debug : virEventRegisterDefaultImpl:231 : registering default event implementation
2013-10-25 10:37:04.932+0000: 18585: debug : virEventPollAddHandle:111 : Used 0 handle slots, adding at least 10 more
2013-10-25 10:37:04.932+0000: 18585: debug : virEventPollInterruptLocked:713 : Skip interrupt, 0 0
2013-10-25 10:37:04.932+0000: 18585: debug : virEventPollAddHandle:136 : EVENT_POLL_ADD_HANDLE: watch=1 fd=4 events=1 cb=0x7f0480ce1a00 opaque=(nil) ff=(nil)
2013-10-25 10:37:04.932+0000: 18585: debug : virEventRegisterImpl:204 : addHandle=0x7f0480ce26e0 updateHandle=0x7f0480ce2580 removeHandle=0x7f0480ce1e80 addTimeout=0x7f0480ce2000 updateTimeout=0x7f0480ce2200 removeTimeout=0x7f0480ce23a0
2013-10-25 10:37:04.932+0000: 18585: debug : virConnectOpenAuth:1459 : name=(null), auth=0x7f048118c640, flags=0
2013-10-25 10:37:04.932+0000: 18585: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f04834c36b0 classname=virConnect
2013-10-25 10:37:04.932+0000: 18585: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f04834c3320 classname=virConnectCloseCallbackData
2013-10-25 10:37:04.932+0000: 18586: debug : virEventRunDefaultImpl:271 : running default event implementation
2013-10-25 10:37:04.932+0000: 18586: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
2013-10-25 10:37:04.932+0000: 18586: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
2013-10-25 10:37:04.932+0000: 18586: debug : virEventPollCleanupHandles:565 : Cleanup 1
2013-10-25 10:37:04.932+0000: 18586: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
2013-10-25 10:37:04.932+0000: 18586: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
2013-10-25 10:37:04.932+0000: 18586: debug : virEventPollCalculateTimeout:364 : No timeout is pending
2013-10-25 10:37:04.932+0000: 18586: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=1 timeout=-1
2013-10-25 10:37:04.932+0000: 18585: debug : do_open:1175 : no name, allowing driver auto-select
2013-10-25 10:37:04.932+0000: 18585: debug : do_open:1217 : trying driver 0 (Test) ...
2013-10-25 10:37:04.933+0000: 18585: debug : do_open:1224 : driver 0 Test returned DECLINED
2013-10-25 10:37:04.933+0000: 18585: debug : do_open:1217 : trying driver 1 (VMWARE) ...
2013-10-25 10:37:04.933+0000: 18585: debug : do_open:1224 : driver 1 VMWARE returned DECLINED
2013-10-25 10:37:04.933+0000: 18585: debug : do_open:1217 : trying driver 2 (ESX) ...
2013-10-25 10:37:04.933+0000: 18585: debug : do_open:1224 : driver 2 ESX returned DECLINED
2013-10-25 10:37:04.933+0000: 18585: debug : do_open:1217 : trying driver 3 (remote) ...
2013-10-25 10:37:04.933+0000: 18585: debug : remoteConnectOpen:998 : Auto-probe remote URI
2013-10-25 10:37:04.933+0000: 18585: debug : remoteConnectOpen:1001 : Auto-spawn user daemon instance
2013-10-25 10:37:04.933+0000: 18585: debug : doRemoteOpen:599 : proceeding with name =
2013-10-25 10:37:04.933+0000: 18585: debug : doRemoteOpen:608 : Connecting with transport 1
2013-10-25 10:37:04.933+0000: 18585: debug : doRemoteOpen:699 : Proceeding with sockname /home/betlor/.cache/libvirt/libvirt-sock
2013-10-25 10:37:04.933+0000: 18585: debug : virNetSocketNewConnectUNIX:555 : Connection refused for /home/betlor/.cache/libvirt/libvirt-sock, trying to spawn /usr/sbin/libvirtd
2013-10-25 10:37:04.933+0000: 18585: debug : virCommandRunAsync:2237 : About to run LC_ALL=C PATH=/usr/local/bin:/usr/bin:/bin:/opt/bin:/usr/x86_64-pc-linux-gnu/gcc-bin/4.6.3:/usr/games/bin HOME=/home/betlor USER=betlor LOGNAME=betlor /usr/sbin/libvirtd --timeout=30
2013-10-25 10:37:04.933+0000: 18585: debug : virFileClose:90 : Closed fd 7
2013-10-25 10:37:04.933+0000: 18585: debug : virCommandRunAsync:2242 : Command result 0, with PID 18587
2013-10-25 10:37:04.933+0000: 18587: debug : virFileClose:90 : Closed fd 3
2013-10-25 10:37:04.933+0000: 18587: debug : virFileClose:90 : Closed fd 4
2013-10-25 10:37:04.933+0000: 18587: debug : virFileClose:90 : Closed fd 5
2013-10-25 10:37:04.933+0000: 18587: debug : virFileClose:90 : Closed fd 6
2013-10-25 10:37:04.933+0000: 18585: debug : virCommandRun:2111 : Result status 0, stdout: '(null)' stderr: '(null)'
2013-10-25 10:37:05.033+0000: 18585: debug : virNetSocketNew:155 : localAddr=0x7fff70487e20 remoteAddr=0x7fff70487eb0 fd=6 errfd=-1 pid=0
2013-10-25 10:37:05.033+0000: 18585: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f04834c5cf0 classname=virNetSocket
2013-10-25 10:37:05.033+0000: 18585: debug : virNetSocketNew:205 : RPC_SOCKET_NEW: sock=0x7f04834c5cf0 fd=6 errfd=-1 pid=0 localAddr=127.0.0.1;0, remoteAddr=127.0.0.1;0
2013-10-25 10:37:05.033+0000: 18585: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f04834c3a00 classname=virNetClient
2013-10-25 10:37:05.033+0000: 18585: debug : virNetClientNew:326 : RPC_CLIENT_NEW: client=0x7f04834c3a00 sock=0x7f04834c5cf0
2013-10-25 10:37:05.033+0000: 18585: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f04834c3a00
2013-10-25 10:37:05.033+0000: 18585: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f04834c5cf0
2013-10-25 10:37:05.033+0000: 18585: debug : virEventPollInterruptLocked:717 : Interrupting
2013-10-25 10:37:05.033+0000: 18585: debug : virEventPollAddHandle:136 : EVENT_POLL_ADD_HANDLE: watch=2 fd=6 events=1 cb=0x7f0480e10f40 opaque=0x7f04834c5cf0 ff=0x7f0480e11000
2013-10-25 10:37:05.033+0000: 18585: debug : virKeepAliveNew:196 : client=0x7f04834c3a00, interval=-1, count=0
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollDispatchHandles:471 : Dispatch 1
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollDispatchHandles:485 : i=0 w=1
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2013-10-25 10:37:05.033+0000: 18585: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f04834c6220 classname=virKeepAlive
2013-10-25 10:37:05.033+0000: 18585: debug : virKeepAliveNew:215 : RPC_KEEPALIVE_NEW: ka=0x7f04834c6220 client=0x7f04834c3a00
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
2013-10-25 10:37:05.033+0000: 18585: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f04834c3a00
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollCleanupHandles:565 : Cleanup 2
2013-10-25 10:37:05.033+0000: 18585: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f04834c3320
2013-10-25 10:37:05.033+0000: 18586: debug : virEventRunDefaultImpl:271 : running default event implementation
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
2013-10-25 10:37:05.033+0000: 18585: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f04834c60e0 classname=virNetClientProgram
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
2013-10-25 10:37:05.033+0000: 18585: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f04834c3990 classname=virNetClientProgram
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollCleanupHandles:565 : Cleanup 2
2013-10-25 10:37:05.033+0000: 18585: debug : virObjectNew:199 : OBJECT_NEW: obj=0x7f04834c38c0 classname=virNetClientProgram
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
2013-10-25 10:37:05.033+0000: 18585: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f04834c60e0
2013-10-25 10:37:05.033+0000: 18585: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f04834c3990
2013-10-25 10:37:05.033+0000: 18585: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f04834c38c0
2013-10-25 10:37:05.033+0000: 18585: debug : doRemoteOpen:816 : Trying authentication
2013-10-25 10:37:05.033+0000: 18585: debug : virNetMessageNew:44 : msg=0x7f04834c67b0 tracked=0
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=1 d=0
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
2013-10-25 10:37:05.033+0000: 18585: debug : virNetMessageEncodePayload:373 : Encode length as 28
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollCalculateTimeout:364 : No timeout is pending
2013-10-25 10:37:05.033+0000: 18585: debug : virNetClientSendInternal:1952 : RPC_CLIENT_MSG_TX_QUEUE: client=0x7f04834c3a00 len=28 prog=536903814 vers=1 proc=66 type=0 status=0 serial=0
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=2 timeout=-1
2013-10-25 10:37:05.033+0000: 18585: debug : virNetClientCallNew:1905 : New call 0x7f04834c6730: msg=0x7f04834c67b0, expectReply=1, nonBlock=0
2013-10-25 10:37:05.033+0000: 18585: debug : virNetClientIO:1714 : Outgoing message prog=536903814 version=1 serial=0 proc=66 type=0 length=28 dispatch=(nil)
2013-10-25 10:37:05.033+0000: 18585: debug : virNetClientIO:1773 : We have the buck head=0x7f04834c6730 call=0x7f04834c6730
2013-10-25 10:37:05.033+0000: 18585: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0
2013-10-25 10:37:05.033+0000: 18585: debug : virEventPollInterruptLocked:717 : Interrupting
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollDispatchHandles:471 : Dispatch 2
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollDispatchHandles:485 : i=0 w=1
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollCleanupHandles:565 : Cleanup 2
2013-10-25 10:37:05.033+0000: 18586: debug : virEventRunDefaultImpl:271 : running default event implementation
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollCleanupHandles:565 : Cleanup 2
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=0 d=0
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollCalculateTimeout:364 : No timeout is pending
2013-10-25 10:37:05.033+0000: 18586: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=1 timeout=-1
2013-10-25 10:37:05.043+0000: 18585: debug : virNetMessageDecodeLength:149 : Got length, now need 36 total (32 more)
2013-10-25 10:37:05.043+0000: 18585: debug : virNetClientCallDispatch:1123 : RPC_CLIENT_MSG_RX: client=0x7f04834c3a00 len=36 prog=536903814 vers=1 proc=66 type=1 status=0 serial=0
2013-10-25 10:37:05.043+0000: 18585: debug : virKeepAliveCheckMessage:374 : ka=0x7f04834c6220, client=0x7f04834c3a00, msg=0x7f04834c3a68
2013-10-25 10:37:05.043+0000: 18585: debug : virNetMessageClear:55 : msg=0x7f04834c3a68 nfds=0
2013-10-25 10:37:05.043+0000: 18585: debug : virNetClientIOEventLoopPassTheBuck:1420 : Giving up the buck 0x7f04834c6730
2013-10-25 10:37:05.043+0000: 18585: debug : virNetClientIOEventLoopPassTheBuck:1434 : No thread to pass the buck to
2013-10-25 10:37:05.043+0000: 18585: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1
2013-10-25 10:37:05.043+0000: 18585: debug : virEventPollInterruptLocked:717 : Interrupting
2013-10-25 10:37:05.043+0000: 18585: debug : virNetClientIO:1803 : All done with our call head=(nil) call=0x7f04834c6730 rv=0
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollDispatchHandles:471 : Dispatch 1
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollDispatchHandles:485 : i=0 w=1
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollCleanupHandles:565 : Cleanup 2
2013-10-25 10:37:05.043+0000: 18585: debug : virNetMessageFree:72 : msg=0x7f04834c67b0 nfds=0 cb=(nil)
2013-10-25 10:37:05.043+0000: 18586: debug : virEventRunDefaultImpl:271 : running default event implementation
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollCleanupHandles:565 : Cleanup 2
2013-10-25 10:37:05.043+0000: 18585: debug : virNetMessageNew:44 : msg=0x7f04834c67b0 tracked=0
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
2013-10-25 10:37:05.043+0000: 18585: debug : virNetMessageEncodePayload:373 : Encode length as 32
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=1 d=0
2013-10-25 10:37:05.043+0000: 18585: debug : virNetClientSendInternal:1952 : RPC_CLIENT_MSG_TX_QUEUE: client=0x7f04834c3a00 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=1
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollCalculateTimeout:364 : No timeout is pending
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=2 timeout=-1
2013-10-25 10:37:05.043+0000: 18585: debug : virNetClientCallNew:1905 : New call 0x7f04834c6730: msg=0x7f04834c67b0, expectReply=1, nonBlock=0
2013-10-25 10:37:05.043+0000: 18585: debug : virNetClientIO:1714 : Outgoing message prog=536903814 version=1 serial=1 proc=60 type=0 length=32 dispatch=(nil)
2013-10-25 10:37:05.043+0000: 18585: debug : virNetClientIO:1773 : We have the buck head=0x7f04834c6730 call=0x7f04834c6730
2013-10-25 10:37:05.043+0000: 18585: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0
2013-10-25 10:37:05.043+0000: 18585: debug : virEventPollInterruptLocked:717 : Interrupting
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollDispatchHandles:471 : Dispatch 2
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollDispatchHandles:485 : i=0 w=1
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollCleanupHandles:565 : Cleanup 2
2013-10-25 10:37:05.043+0000: 18586: debug : virEventRunDefaultImpl:271 : running default event implementation
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollCleanupHandles:565 : Cleanup 2
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=0 d=0
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollCalculateTimeout:364 : No timeout is pending
2013-10-25 10:37:05.043+0000: 18586: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=1 timeout=-1
2013-10-25 10:37:05.044+0000: 18585: debug : virNetMessageDecodeLength:149 : Got length, now need 32 total (28 more)
2013-10-25 10:37:05.044+0000: 18585: debug : virNetClientCallDispatch:1123 : RPC_CLIENT_MSG_RX: client=0x7f04834c3a00 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=1
2013-10-25 10:37:05.044+0000: 18585: debug : virKeepAliveCheckMessage:374 : ka=0x7f04834c6220, client=0x7f04834c3a00, msg=0x7f04834c3a68
2013-10-25 10:37:05.044+0000: 18585: debug : virNetMessageClear:55 : msg=0x7f04834c3a68 nfds=0
2013-10-25 10:37:05.044+0000: 18585: debug : virNetClientIOEventLoopPassTheBuck:1420 : Giving up the buck 0x7f04834c6730
2013-10-25 10:37:05.044+0000: 18585: debug : virNetClientIOEventLoopPassTheBuck:1434 : No thread to pass the buck to
2013-10-25 10:37:05.044+0000: 18585: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1
2013-10-25 10:37:05.044+0000: 18585: debug : virEventPollInterruptLocked:717 : Interrupting
2013-10-25 10:37:05.044+0000: 18585: debug : virNetClientIO:1803 : All done with our call head=(nil) call=0x7f04834c6730 rv=0
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
2013-10-25 10:37:05.044+0000: 18585: debug : virNetMessageFree:72 : msg=0x7f04834c67b0 nfds=0 cb=(nil)
2013-10-25 10:37:05.044+0000: 18585: debug : doRemoteOpen:842 : Trying to open URI
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollDispatchHandles:471 : Dispatch 1
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollDispatchHandles:485 : i=0 w=1
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
2013-10-25 10:37:05.044+0000: 18585: debug : virNetMessageNew:44 : msg=0x7f04834c5ed0 tracked=0
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCleanupHandles:565 : Cleanup 2
2013-10-25 10:37:05.044+0000: 18586: debug : virEventRunDefaultImpl:271 : running default event implementation
2013-10-25 10:37:05.044+0000: 18585: debug : virNetMessageEncodePayload:373 : Encode length as 40
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
2013-10-25 10:37:05.044+0000: 18585: debug : virNetClientSendInternal:1952 : RPC_CLIENT_MSG_TX_QUEUE: client=0x7f04834c3a00 len=40 prog=536903814 vers=1 proc=1 type=0 status=0 serial=2
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
2013-10-25 10:37:05.044+0000: 18585: debug : virNetClientCallNew:1905 : New call 0x7f04834c61c0: msg=0x7f04834c5ed0, expectReply=1, nonBlock=0
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCleanupHandles:565 : Cleanup 2
2013-10-25 10:37:05.044+0000: 18585: debug : virNetClientIO:1714 : Outgoing message prog=536903814 version=1 serial=2 proc=1 type=0 length=40 dispatch=(nil)
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
2013-10-25 10:37:05.044+0000: 18585: debug : virNetClientIO:1773 : We have the buck head=0x7f04834c61c0 call=0x7f04834c61c0
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=1 d=0
2013-10-25 10:37:05.044+0000: 18585: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCalculateTimeout:364 : No timeout is pending
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=2 timeout=-1
2013-10-25 10:37:05.044+0000: 18585: debug : virEventPollInterruptLocked:717 : Interrupting
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollDispatchHandles:471 : Dispatch 2
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollDispatchHandles:485 : i=0 w=1
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCleanupHandles:565 : Cleanup 2
2013-10-25 10:37:05.044+0000: 18586: debug : virEventRunDefaultImpl:271 : running default event implementation
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCleanupHandles:565 : Cleanup 2
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=0 d=0
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCalculateTimeout:364 : No timeout is pending
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=1 timeout=-1
2013-10-25 10:37:05.044+0000: 18585: debug : virNetMessageDecodeLength:149 : Got length, now need 28 total (24 more)
2013-10-25 10:37:05.044+0000: 18585: debug : virNetClientCallDispatch:1123 : RPC_CLIENT_MSG_RX: client=0x7f04834c3a00 len=28 prog=536903814 vers=1 proc=1 type=1 status=0 serial=2
2013-10-25 10:37:05.044+0000: 18585: debug : virKeepAliveCheckMessage:374 : ka=0x7f04834c6220, client=0x7f04834c3a00, msg=0x7f04834c3a68
2013-10-25 10:37:05.044+0000: 18585: debug : virNetMessageClear:55 : msg=0x7f04834c3a68 nfds=0
2013-10-25 10:37:05.044+0000: 18585: debug : virNetClientIOEventLoopPassTheBuck:1420 : Giving up the buck 0x7f04834c61c0
2013-10-25 10:37:05.044+0000: 18585: debug : virNetClientIOEventLoopPassTheBuck:1434 : No thread to pass the buck to
2013-10-25 10:37:05.044+0000: 18585: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1
2013-10-25 10:37:05.044+0000: 18585: debug : virEventPollInterruptLocked:717 : Interrupting
2013-10-25 10:37:05.044+0000: 18585: debug : virNetClientIO:1803 : All done with our call head=(nil) call=0x7f04834c61c0 rv=0
2013-10-25 10:37:05.044+0000: 18585: debug : virNetMessageFree:72 : msg=0x7f04834c5ed0 nfds=0 cb=(nil)
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
2013-10-25 10:37:05.044+0000: 18585: debug : doRemoteOpen:853 : Trying to query remote URI
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollDispatchHandles:471 : Dispatch 1
2013-10-25 10:37:05.044+0000: 18585: debug : virNetMessageNew:44 : msg=0x7f04834c62b0 tracked=0
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollDispatchHandles:485 : i=0 w=1
2013-10-25 10:37:05.044+0000: 18585: debug : virNetMessageEncodePayload:373 : Encode length as 28
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2013-10-25 10:37:05.044+0000: 18585: debug : virNetClientSendInternal:1952 : RPC_CLIENT_MSG_TX_QUEUE: client=0x7f04834c3a00 len=28 prog=536903814 vers=1 proc=110 type=0 status=0 serial=3
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
2013-10-25 10:37:05.044+0000: 18585: debug : virNetClientCallNew:1905 : New call 0x7f04834c5c70: msg=0x7f04834c62b0, expectReply=1, nonBlock=0
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
2013-10-25 10:37:05.044+0000: 18585: debug : virNetClientIO:1714 : Outgoing message prog=536903814 version=1 serial=3 proc=110 type=0 length=28 dispatch=(nil)
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCleanupHandles:565 : Cleanup 2
2013-10-25 10:37:05.044+0000: 18585: debug : virNetClientIO:1773 : We have the buck head=0x7f04834c5c70 call=0x7f04834c5c70
2013-10-25 10:37:05.044+0000: 18586: debug : virEventRunDefaultImpl:271 : running default event implementation
2013-10-25 10:37:05.044+0000: 18585: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCleanupHandles:565 : Cleanup 2
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=1 d=0
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCalculateTimeout:364 : No timeout is pending
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=2 timeout=-1
2013-10-25 10:37:05.044+0000: 18585: debug : virEventPollInterruptLocked:717 : Interrupting
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollDispatchHandles:471 : Dispatch 2
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollDispatchHandles:485 : i=0 w=1
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
2013-10-25 10:37:05.044+0000: 18586: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollCleanupHandles:565 : Cleanup 2
2013-10-25 10:37:05.048+0000: 18586: debug : virEventRunDefaultImpl:271 : running default event implementation
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollCleanupHandles:565 : Cleanup 2
2013-10-25 10:37:05.048+0000: 18585: debug : virNetMessageDecodeLength:149 : Got length, now need 48 total (44 more)
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=0 d=0
2013-10-25 10:37:05.048+0000: 18585: debug : virNetClientCallDispatch:1123 : RPC_CLIENT_MSG_RX: client=0x7f04834c3a00 len=48 prog=536903814 vers=1 proc=110 type=1 status=0 serial=3
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
2013-10-25 10:37:05.048+0000: 18585: debug : virKeepAliveCheckMessage:374 : ka=0x7f04834c6220, client=0x7f04834c3a00, msg=0x7f04834c3a68
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollCalculateTimeout:364 : No timeout is pending
2013-10-25 10:37:05.048+0000: 18585: debug : virNetMessageClear:55 : msg=0x7f04834c3a68 nfds=0
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=1 timeout=-1
2013-10-25 10:37:05.048+0000: 18585: debug : virNetClientIOEventLoopPassTheBuck:1420 : Giving up the buck 0x7f04834c5c70
2013-10-25 10:37:05.048+0000: 18585: debug : virNetClientIOEventLoopPassTheBuck:1434 : No thread to pass the buck to
2013-10-25 10:37:05.048+0000: 18585: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1
2013-10-25 10:37:05.048+0000: 18585: debug : virEventPollInterruptLocked:717 : Interrupting
2013-10-25 10:37:05.048+0000: 18585: debug : virNetClientIO:1803 : All done with our call head=(nil) call=0x7f04834c5c70 rv=0
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollDispatchHandles:471 : Dispatch 1
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollDispatchHandles:485 : i=0 w=1
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2013-10-25 10:37:05.048+0000: 18585: debug : virNetMessageFree:72 : msg=0x7f04834c62b0 nfds=0 cb=(nil)
2013-10-25 10:37:05.048+0000: 18585: debug : doRemoteOpen:861 : Auto-probed URI is qemu:///session
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollCleanupHandles:565 : Cleanup 2
2013-10-25 10:37:05.048+0000: 18586: debug : virEventRunDefaultImpl:271 : running default event implementation
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
2013-10-25 10:37:05.048+0000: 18585: debug : do_open:1224 : driver 3 remote returned SUCCESS
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollCleanupHandles:565 : Cleanup 2
2013-10-25 10:37:05.048+0000: 18585: debug : do_open:1250 : network driver 0 Test returned DECLINED
2013-10-25 10:37:05.048+0000: 18585: debug : do_open:1250 : network driver 1 ESX returned DECLINED
2013-10-25 10:37:05.048+0000: 18585: debug : do_open:1250 : network driver 2 remote returned SUCCESS
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
2013-10-25 10:37:05.048+0000: 18585: debug : do_open:1266 : interface driver 0 Test returned DECLINED
2013-10-25 10:37:05.048+0000: 18585: debug : do_open:1266 : interface driver 1 ESX returned DECLINED
2013-10-25 10:37:05.048+0000: 18585: debug : do_open:1266 : interface driver 2 remote returned SUCCESS
2013-10-25 10:37:05.048+0000: 18585: debug : do_open:1283 : storage driver 0 Test returned DECLINED
2013-10-25 10:37:05.048+0000: 18585: debug : do_open:1283 : storage driver 1 ESX returned DECLINED
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=1 d=0
2013-10-25 10:37:05.048+0000: 18585: debug : do_open:1283 : storage driver 2 remote returned SUCCESS
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollCalculateTimeout:332 : Calculate expiry of 0 timers
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollCalculateTimeout:364 : No timeout is pending
2013-10-25 10:37:05.048+0000: 18585: debug : do_open:1300 : node driver 0 Test returned DECLINED
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollRunOnce:630 : EVENT_POLL_RUN: nhandles=2 timeout=-1
2013-10-25 10:37:05.048+0000: 18585: debug : do_open:1300 : node driver 1 ESX returned DECLINED
2013-10-25 10:37:05.048+0000: 18585: debug : do_open:1300 : node driver 2 remote returned SUCCESS
2013-10-25 10:37:05.048+0000: 18585: debug : do_open:1317 : secret driver 0 Test returned DECLINED
2013-10-25 10:37:05.048+0000: 18585: debug : do_open:1317 : secret driver 1 ESX returned DECLINED
2013-10-25 10:37:05.048+0000: 18585: debug : do_open:1317 : secret driver 2 remote returned SUCCESS
2013-10-25 10:37:05.048+0000: 18585: debug : do_open:1334 : nwfilter driver 0 Test returned DECLINED
2013-10-25 10:37:05.048+0000: 18585: debug : do_open:1334 : nwfilter driver 1 ESX returned DECLINED
2013-10-25 10:37:05.048+0000: 18585: debug : do_open:1334 : nwfilter driver 2 remote returned SUCCESS
2013-10-25 10:37:05.048+0000: 18585: debug : virConnectRegisterCloseCallback:21408 : conn=0x7f04834c36b0
2013-10-25 10:37:05.048+0000: 18585: debug : virObjectRef:293 : OBJECT_REF: obj=0x7f04834c36b0
2013-10-25 10:37:05.048+0000: 18585: debug : virConnectIsAlive:21356 : conn=0x7f04834c36b0
2013-10-25 10:37:05.048+0000: 18585: debug : virConnectListAllDomains:9379 : conn=0x7f04834c36b0, domains=0x7f04834c6350, flags=3
2013-10-25 10:37:05.048+0000: 18585: debug : virNetMessageNew:44 : msg=0x7f04834c6390 tracked=0
2013-10-25 10:37:05.048+0000: 18585: debug : virNetMessageEncodePayload:373 : Encode length as 36
2013-10-25 10:37:05.048+0000: 18585: debug : virNetClientSendInternal:1952 : RPC_CLIENT_MSG_TX_QUEUE: client=0x7f04834c3a00 len=36 prog=536903814 vers=1 proc=273 type=0 status=0 serial=4
2013-10-25 10:37:05.048+0000: 18585: debug : virNetClientCallNew:1905 : New call 0x7f04834c37e0: msg=0x7f04834c6390, expectReply=1, nonBlock=0
2013-10-25 10:37:05.048+0000: 18585: debug : virNetClientIO:1714 : Outgoing message prog=536903814 version=1 serial=4 proc=273 type=0 length=36 dispatch=(nil)
2013-10-25 10:37:05.048+0000: 18585: debug : virNetClientIO:1773 : We have the buck head=0x7f04834c37e0 call=0x7f04834c37e0
2013-10-25 10:37:05.048+0000: 18585: debug : virEventPollUpdateHandle:147 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0
2013-10-25 10:37:05.048+0000: 18585: debug : virEventPollInterruptLocked:717 : Interrupting
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollRunOnce:641 : Poll got 1 event(s)
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollDispatchTimeouts:426 : Dispatch 0
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollDispatchHandles:471 : Dispatch 2
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollDispatchHandles:485 : i=0 w=1
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollDispatchHandles:499 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollCleanupHandles:565 : Cleanup 2
2013-10-25 10:37:05.048+0000: 18586: debug : virEventRunDefaultImpl:271 : running default event implementation
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollCleanupTimeouts:517 : Cleanup 0
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollCleanupTimeouts:553 : Found 0 out of 0 timeout slots used, releasing 0
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollCleanupHandles:565 : Cleanup 2
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollMakePollFDs:394 : Prepare n=0 w=1, f=4 e=1 d=0
2013-10-25 10:37:05.048+0000: 18586: debug : virEventPollMakePollFDs:394 : Prepare n=1 w=2, f=6 e=0 d=0
2013-10-25 10:
Back to top
View user's profile Send private message
_______0
Guru
Guru


Joined: 15 Oct 2012
Posts: 521

PostPosted: Sun Oct 27, 2013 8:37 pm    Post subject: Reply with quote

seems permissions problem. Are you part of the kvm group? Not sure if libvirt requires extra group. But check whether your user is in the kvm group.

Also, are you aware that you can use qemu-kvm without libvirt?? libvirt looks like a crazy extra layer of complexity.
Back to top
View user's profile Send private message
Display posts from previous:   
Reply to topic    Gentoo Forums Forum Index Unsupported Software All times are GMT
Page 1 of 1

 
Jump to:  
You cannot post new topics in this forum
You cannot reply to topics in this forum
You cannot edit your posts in this forum
You cannot delete your posts in this forum
You cannot vote in polls in this forum