Hello,

starting the virt-manager gives error.

Running as root is not possible because virt-manager fails to connect to
dbus and bails even earlier. --no-dbus does not help with that.

I added (xend-unix-server yes) in /etc/xen/xend-config.sxp because it is
recommended in README.Debian and restarted the xend. It has no effect.

# xm list
Name                                        ID   Mem VCPUs      State   Time(s)
Domain-0                                     0  7823     4     r-----   1116.4

$ LIBVIRT_DEBUG=1 virt-manager --debug --no-fork
2012-09-19 11:21:07.936+0000: 14527: info : libvirt version: 0.10.1
2012-09-19 11:21:07.936+0000: 14527: debug : virInitialize:421 : register drivers
2012-09-19 11:21:07.936+0000: 14527: debug : virRegisterDriver:729 : driver=0x7f0a2a5a5ba0 name=Test
2012-09-19 11:21:07.936+0000: 14527: debug : virRegisterDriver:744 : registering Test as driver 0
2012-09-19 11:21:07.936+0000: 14527: debug : virRegisterNetworkDriver:561 : registering Test as network driver 0
2012-09-19 11:21:07.936+0000: 14527: debug : virRegisterInterfaceDriver:591 : registering Test as interface driver 0
2012-09-19 11:21:07.936+0000: 14527: debug : virRegisterStorageDriver:621 : registering Test as storage driver 0
2012-09-19 11:21:07.936+0000: 14527: debug : virRegisterDeviceMonitor:651 : registering Test as device driver 0
2012-09-19 11:21:07.936+0000: 14527: debug : virRegisterSecretDriver:681 : registering Test as secret driver 0
2012-09-19 11:21:07.936+0000: 14527: debug : virRegisterNWFilterDriver:711 : registering Test as network filter driver 0
2012-09-19 11:21:07.936+0000: 14527: debug : virRegisterDriver:729 : driver=0x7f0a2a5a7080 name=OPENVZ
2012-09-19 11:21:07.936+0000: 14527: debug : virRegisterDriver:744 : registering OPENVZ as driver 1
2012-09-19 11:21:07.936+0000: 14527: debug : virRegisterDriver:729 : driver=0x7f0a2a5a7600 name=VMWARE
2012-09-19 11:21:07.936+0000: 14527: debug : virRegisterDriver:744 : registering VMWARE as driver 2
2012-09-19 11:21:07.937+0000: 14527: debug : vboxRegister:131 : VBoxCGlueInit failed, using dummy driver
2012-09-19 11:21:07.937+0000: 14527: debug : virRegisterDriver:729 : driver=0x7f0a2a5a7b80 name=VBOX
2012-09-19 11:21:07.937+0000: 14527: debug : virRegisterDriver:744 : registering VBOX as driver 3
2012-09-19 11:21:07.937+0000: 14527: debug : virRegisterNetworkDriver:561 : registering VBOX as network driver 1
2012-09-19 11:21:07.937+0000: 14527: debug : virRegisterStorageDriver:621 : registering VBOX as storage driver 1
2012-09-19 11:21:07.937+0000: 14527: debug : parallelsRegister:1714 : Can't find prlctl command in the PATH env
2012-09-19 11:21:07.937+0000: 14527: debug : virRegisterDriver:729 : driver=0x7f0a2a5a64e0 name=remote
2012-09-19 11:21:07.937+0000: 14527: debug : virRegisterDriver:744 : registering remote as driver 4
2012-09-19 11:21:07.937+0000: 14527: debug : virRegisterNetworkDriver:561 : registering remote as network driver 2
2012-09-19 11:21:07.937+0000: 14527: debug : virRegisterInterfaceDriver:591 : registering remote as interface driver 1
2012-09-19 11:21:07.937+0000: 14527: debug : virRegisterStorageDriver:621 : registering remote as storage driver 2
2012-09-19 11:21:07.937+0000: 14527: debug : virRegisterDeviceMonitor:651 : registering remote as device driver 1
2012-09-19 11:21:07.937+0000: 14527: debug : virRegisterSecretDriver:681 : registering remote as secret driver 1
2012-09-19 11:21:07.937+0000: 14527: debug : virRegisterNWFilterDriver:711 : registering remote as network filter driver 1
2012-09-19 13:21:08,028 (cli:71): virt-manager startup
2012-09-19 13:21:08,029 (virt-manager:297): Launched as: /usr/share/virt-manager/virt-manager.py --debug --no-fork
2012-09-19 13:21:08,029 (virt-manager:298): GTK version: (2, 24, 10)
2012-09-19 13:21:08,029 (virt-manager:299): virt-manager version: 0.9.4
2012-09-19 13:21:08,029 (virt-manager:300): virtManager import: <module 'virtManager' from '/usr/share/virt-manager/virtManager/__init__.pyc'>
2012-09-19 13:21:08,114 (cli:118): virtinst version: 0.600.3
2012-09-19 13:21:08,114 (cli:119): virtinst import: <module 'virtinst' from '/usr/lib/pymodules/python2.7/virtinst/__init__.pyc'>
2012-09-19 13:21:08,154 (keyring:30): gnomekeyring bindings not installed, no keyring support
2012-09-19 11:21:08.157+0000: 14527: debug : virGetVersion:888 : libVir=0x7fffa2b3cda0, type=(null), typeVer=(nil)
2012-09-19 13:21:08,221 (inspection:82): waiting
2012-09-19 13:21:08,224 (systray:138): Showing systray: False
2012-09-19 13:21:08,224 (engine:199): About to connect to uris ['xen:///']
2012-09-19 11:21:08.230+0000: 14527: debug : virEventRegisterImpl:177 : addHandle=0x7f0a2a5ccb20 updateHandle=0x7f0a2a5c3d00 removeHandle=0x7f0a2a5c3fb0 addTimeout=0x7f0a2a5cc910 updateTimeout=0x7f0a2a5c3bd0 removeTimeout=0x7f0a2a5c3e30
2012-09-19 13:21:08,254 (manager:172): Showing manager
2012-09-19 13:21:08,299 (engine:324): window counter incremented to 1
2012-09-19 13:21:08,300 (manager:172): Showing manager
2012-09-19 13:21:08,301 (connection:963): Scheduling background open thread for xen:///
2012-09-19 13:21:08,301 (connection:1019): Background 'open connection' thread is running
2012-09-19 11:21:08.302+0000: 14530: debug : virGetVersion:888 : libVir=0x7f0a1705adc0, type=(null), typeVer=(nil)
2012-09-19 11:21:08.302+0000: 14530: debug : virConnectOpenAuth:1403 : name=xen:///, auth=0x7f0a1705b0e0, flags=0
2012-09-19 11:21:08.302+0000: 14530: debug : virObjectNew:110 : OBJECT_NEW: obj=0x278ba40 classname=virConnect
2012-09-19 11:21:08.302+0000: 14530: debug : do_open:1127 : name "xen:///" to URI components:
  scheme xen
  server (null)
  user (null)
  port 0
  path /

2012-09-19 11:21:08.302+0000: 14530: debug : do_open:1174 : trying driver 0 (Test) ...
2012-09-19 11:21:08.302+0000: 14530: debug : do_open:1180 : driver 0 Test returned DECLINED
2012-09-19 11:21:08.302+0000: 14530: debug : do_open:1174 : trying driver 1 (OPENVZ) ...
2012-09-19 11:21:08.302+0000: 14530: debug : do_open:1180 : driver 1 OPENVZ returned DECLINED
2012-09-19 11:21:08.303+0000: 14530: debug : do_open:1174 : trying driver 2 (VMWARE) ...
2012-09-19 11:21:08.303+0000: 14530: debug : do_open:1180 : driver 2 VMWARE returned DECLINED
2012-09-19 11:21:08.303+0000: 14530: debug : do_open:1174 : trying driver 3 (VBOX) ...
2012-09-19 11:21:08.303+0000: 14530: debug : do_open:1180 : driver 3 VBOX returned DECLINED
2012-09-19 11:21:08.303+0000: 14530: debug : do_open:1174 : trying driver 4 (remote) ...
2012-09-19 11:21:08.303+0000: 14530: debug : doRemoteOpen:576 : proceeding with name = xen:///
2012-09-19 11:21:08.303+0000: 14530: debug : doRemoteOpen:585 : Connecting with transport 1
2012-09-19 11:21:08.303+0000: 14530: debug : doRemoteOpen:661 : Proceeding with sockname /var/run/libvirt/libvirt-sock
2012-09-19 11:21:08.303+0000: 14530: debug : virNetSocketNew:146 : localAddr=0x7f0a1705ab60 remoteAddr=0x7f0a1705abf0 fd=13 errfd=-1 pid=0
2012-09-19 11:21:08.303+0000: 14530: debug : virObjectNew:110 : OBJECT_NEW: obj=0x278bdf0 classname=virNetSocket
2012-09-19 11:21:08.303+0000: 14530: debug : virNetSocketNew:203 : RPC_SOCKET_NEW: sock=0x278bdf0 fd=13 errfd=-1 pid=0 localAddr=127.0.0.1;0, remoteAddr=127.0.0.1;0
2012-09-19 11:21:08.303+0000: 14530: debug : virObjectNew:110 : OBJECT_NEW: obj=0x26a3770 classname=virNetClient
2012-09-19 11:21:08.303+0000: 14530: debug : virNetClientNew:342 : RPC_CLIENT_NEW: client=0x26a3770 sock=0x278bdf0
2012-09-19 11:21:08.303+0000: 14530: debug : virObjectRef:168 : OBJECT_REF: obj=0x26a3770
2012-09-19 11:21:08.303+0000: 14530: debug : virObjectRef:168 : OBJECT_REF: obj=0x278bdf0
2012-09-19 11:21:08.303+0000: 14530: debug : virKeepAliveNew:212 : client=0x26a3770, interval=-1, count=0
2012-09-19 11:21:08.303+0000: 14530: debug : virObjectNew:110 : OBJECT_NEW: obj=0x278d460 classname=virKeepAlive
2012-09-19 11:21:08.303+0000: 14530: debug : virKeepAliveNew:236 : RPC_KEEPALIVE_NEW: ka=0x278d460 client=0x26a3770
2012-09-19 11:21:08.303+0000: 14530: debug : virObjectRef:168 : OBJECT_REF: obj=0x26a3770
2012-09-19 11:21:08.303+0000: 14530: debug : virObjectNew:110 : OBJECT_NEW: obj=0x278d4f0 classname=virNetClientProgram
2012-09-19 11:21:08.303+0000: 14530: debug : virObjectNew:110 : OBJECT_NEW: obj=0x261ed60 classname=virNetClientProgram
2012-09-19 11:21:08.304+0000: 14530: debug : virObjectRef:168 : OBJECT_REF: obj=0x278d4f0
2012-09-19 11:21:08.304+0000: 14530: debug : virObjectRef:168 : OBJECT_REF: obj=0x261ed60
2012-09-19 11:21:08.304+0000: 14530: debug : doRemoteOpen:759 : Trying authentication
2012-09-19 11:21:08.304+0000: 14530: debug : virNetMessageNew:45 : msg=0x7f0a0c001820 tracked=0
2012-09-19 11:21:08.304+0000: 14530: debug : virNetMessageEncodePayload:358 : Encode length as 28
2012-09-19 11:21:08.304+0000: 14530: debug : virNetClientSendInternal:1952 : RPC_CLIENT_MSG_TX_QUEUE: client=0x26a3770 len=28 prog=536903814 vers=1 proc=66 type=0 status=0 serial=0
2012-09-19 11:21:08.304+0000: 14530: debug : virNetClientCallNew:1905 : New call 0x7f0a0c0008b0: msg=0x7f0a0c001820, expectReply=1, nonBlock=0
2012-09-19 11:21:08.304+0000: 14530: debug : virNetClientIO:1715 : Outgoing message prog=536903814 version=1 serial=0 proc=66 type=0 length=28 dispatch=(nil)
2012-09-19 11:21:08.304+0000: 14530: debug : virNetClientIO:1774 : We have the buck head=0x7f0a0c0008b0 call=0x7f0a0c0008b0
2012-09-19 11:21:08.305+0000: 14530: debug : virNetMessageDecodeLength:152 : Got length, now need 36 total (32 more)
2012-09-19 11:21:08.305+0000: 14530: debug : virNetClientCallDispatch:1127 : RPC_CLIENT_MSG_RX: client=0x26a3770 len=36 prog=536903814 vers=1 proc=66 type=1 status=0 serial=0
2012-09-19 11:21:08.305+0000: 14530: debug : virKeepAliveCheckMessage:384 : ka=0x278d460, client=0x26a3770, msg=0x26a37d8
2012-09-19 11:21:08.305+0000: 14530: debug : virNetClientIOEventLoopPassTheBuck:1421 : Giving up the buck 0x7f0a0c0008b0
2012-09-19 11:21:08.305+0000: 14530: debug : virNetClientIOEventLoopPassTheBuck:1435 : No thread to pass the buck to
2012-09-19 11:21:08.305+0000: 14530: debug : virNetClientIO:1804 : All done with our call head=(nil) call=0x7f0a0c0008b0 rv=0
2012-09-19 11:21:08.305+0000: 14530: debug : virNetMessageFree:73 : msg=0x7f0a0c001820 nfds=0 cb=(nil)
2012-09-19 11:21:08.305+0000: 14530: debug : virNetMessageNew:45 : msg=0x7f0a0c001820 tracked=0
2012-09-19 11:21:08.305+0000: 14530: debug : virNetMessageEncodePayload:358 : Encode length as 32
2012-09-19 11:21:08.305+0000: 14530: debug : virNetClientSendInternal:1952 : RPC_CLIENT_MSG_TX_QUEUE: client=0x26a3770 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=1
2012-09-19 11:21:08.305+0000: 14530: debug : virNetClientCallNew:1905 : New call 0x7f0a0c0008b0: msg=0x7f0a0c001820, expectReply=1, nonBlock=0
2012-09-19 11:21:08.305+0000: 14530: debug : virNetClientIO:1715 : Outgoing message prog=536903814 version=1 serial=1 proc=60 type=0 length=32 dispatch=(nil)
2012-09-19 11:21:08.305+0000: 14530: debug : virNetClientIO:1774 : We have the buck head=0x7f0a0c0008b0 call=0x7f0a0c0008b0
2012-09-19 11:21:08.306+0000: 14530: debug : virNetMessageDecodeLength:152 : Got length, now need 32 total (28 more)
2012-09-19 11:21:08.306+0000: 14530: debug : virNetClientCallDispatch:1127 : RPC_CLIENT_MSG_RX: client=0x26a3770 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=1
2012-09-19 11:21:08.306+0000: 14530: debug : virKeepAliveCheckMessage:384 : ka=0x278d460, client=0x26a3770, msg=0x26a37d8
2012-09-19 11:21:08.306+0000: 14530: debug : virNetClientIOEventLoopPassTheBuck:1421 : Giving up the buck 0x7f0a0c0008b0
2012-09-19 11:21:08.306+0000: 14530: debug : virNetClientIOEventLoopPassTheBuck:1435 : No thread to pass the buck to
2012-09-19 11:21:08.306+0000: 14530: debug : virNetClientIO:1804 : All done with our call head=(nil) call=0x7f0a0c0008b0 rv=0
2012-09-19 11:21:08.306+0000: 14530: debug : virNetMessageFree:73 : msg=0x7f0a0c001820 nfds=0 cb=(nil)
2012-09-19 11:21:08.306+0000: 14530: debug : doRemoteOpen:785 : Trying to open URI xen:///
2012-09-19 11:21:08.306+0000: 14530: debug : virNetMessageNew:45 : msg=0x7f0a0c001890 tracked=0
2012-09-19 11:21:08.306+0000: 14530: debug : virNetMessageEncodePayload:358 : Encode length as 48
2012-09-19 11:21:08.306+0000: 14530: debug : virNetClientSendInternal:1952 : RPC_CLIENT_MSG_TX_QUEUE: client=0x26a3770 len=48 prog=536903814 vers=1 proc=1 type=0 status=0 serial=2
2012-09-19 11:21:08.306+0000: 14530: debug : virNetClientCallNew:1905 : New call 0x7f0a0c0008b0: msg=0x7f0a0c001890, expectReply=1, nonBlock=0
2012-09-19 11:21:08.306+0000: 14530: debug : virNetClientIO:1715 : Outgoing message prog=536903814 version=1 serial=2 proc=1 type=0 length=48 dispatch=(nil)
2012-09-19 11:21:08.307+0000: 14530: debug : virNetClientIO:1774 : We have the buck head=0x7f0a0c0008b0 call=0x7f0a0c0008b0
2012-09-19 11:21:08.307+0000: 14530: debug : virNetMessageDecodeLength:152 : Got length, now need 224 total (220 more)
2012-09-19 11:21:08.307+0000: 14530: debug : virNetClientCallDispatch:1127 : RPC_CLIENT_MSG_RX: client=0x26a3770 len=224 prog=536903814 vers=1 proc=1 type=1 status=1 serial=2
2012-09-19 11:21:08.308+0000: 14530: debug : virKeepAliveCheckMessage:384 : ka=0x278d460, client=0x26a3770, msg=0x26a37d8
2012-09-19 11:21:08.308+0000: 14530: debug : virNetClientIOEventLoopPassTheBuck:1421 : Giving up the buck 0x7f0a0c0008b0
2012-09-19 11:21:08.308+0000: 14530: debug : virNetClientIOEventLoopPassTheBuck:1435 : No thread to pass the buck to
2012-09-19 11:21:08.308+0000: 14530: debug : virNetClientIO:1804 : All done with our call head=(nil) call=0x7f0a0c0008b0 rv=0
2012-09-19 11:21:08.308+0000: 14530: debug : virNetMessageFree:73 : msg=0x7f0a0c001890 nfds=0 cb=(nil)
2012-09-19 11:21:08.308+0000: 14530: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x278d4f0
2012-09-19 11:21:08.308+0000: 14530: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x261ed60
2012-09-19 11:21:08.308+0000: 14530: debug : virNetClientCloseInternal:698 : client=0x26a3770 wantclose=0
2012-09-19 11:21:08.308+0000: 14530: debug : virNetClientMarkClose:646 : client=0x26a3770, reason=3
2012-09-19 11:21:08.308+0000: 14530: debug : virNetClientIOEventLoopPassTheBuck:1421 : Giving up the buck (nil)
2012-09-19 11:21:08.308+0000: 14530: debug : virNetClientIOEventLoopPassTheBuck:1435 : No thread to pass the buck to
2012-09-19 11:21:08.308+0000: 14530: debug : virNetClientCloseLocked:659 : client=0x26a3770, sock=0x278bdf0, reason=3
2012-09-19 11:21:08.308+0000: 14530: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x278bdf0
2012-09-19 11:21:08.308+0000: 14530: debug : virObjectRef:168 : OBJECT_REF: obj=0x26a3770
2012-09-19 11:21:08.308+0000: 14530: debug : virKeepAliveStop:320 : RPC_KEEPALIVE_STOP: ka=0x278d460 client=0x26a3770
2012-09-19 11:21:08.308+0000: 14530: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x278d460
2012-09-19 11:21:08.308+0000: 14530: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x278d460
2012-09-19 11:21:08.308+0000: 14530: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x26a3770
2012-09-19 11:21:08.308+0000: 14530: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x26a3770
2012-09-19 11:21:08.308+0000: 14530: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x26a3770
2012-09-19 11:21:08.308+0000: 14530: debug : do_open:1180 : driver 4 remote returned ERROR
2012-09-19 11:21:08.308+0000: 14530: debug : virObjectUnref:135 : OBJECT_UNREF: obj=0x278ba40
2012-09-19 11:21:08.308+0000: 14530: debug : virObjectUnref:137 : OBJECT_DISPOSE: obj=0x278ba40
2012-09-19 13:21:08,312 (connection:1070): Background open thread complete, scheduling notify
2012-09-19 13:21:08,365 (connection:1075): Notifying open result
2012-09-19 13:21:08,365 (error:80): dialog message: Unable to connect to libvirt.

Unable to issue hypervisor ioctl 3166208: Function not implemented

Verify that:
 - A Xen host kernel was booted
 - The Xen service has been started : Unable to connect to libvirt.

Unable to issue hypervisor ioctl 3166208: Function not implemented

Verify that:
 - A Xen host kernel was booted
 - The Xen service has been started

Libvirt URI is: xen:///

Traceback (most recent call last):
  File "/usr/share/virt-manager/virtManager/connection.py", line 1027, in _open_thread
    self.vmm = self._try_open()
  File "/usr/share/virt-manager/virtManager/connection.py", line 1009, in _try_open
    flags)
  File "/usr/lib/python2.7/dist-packages/libvirt.py", line 102, in openAuth
    if ret is None:raise libvirtError('virConnectOpenAuth() failed')
libvirtError: Unable to issue hypervisor ioctl 3166208: Function not implemented
2012-09-19 13:21:18,887 (manager:185): Closing manager
2012-09-19 13:21:18,887 (engine:328): window counter decremented to 0
2012-09-19 13:21:18,888 (manager:185): Closing manager
2012-09-19 13:21:18,891 (engine:403): Exiting app normally.

Also sending excerpt from server-side log that seems relevant to xen:


2012-09-19 11:37:56.043+0000: 14927: debug : virConnectOpen:1331 : name=xen:///
2012-09-19 11:37:56.043+0000: 14927: debug : virObjectNew:110 : OBJECT_NEW: obj=0x18fe990 classname=virConnect
2012-09-19 11:37:56.043+0000: 14927: debug : virConnectGetConfigFile:953 : Loading config file '/etc/libvirt/libvirt.conf'
2012-09-19 11:37:56.043+0000: 14927: debug : virFileClose:72 : Closed fd 18
2012-09-19 11:37:56.043+0000: 14927: debug : do_open:1127 : name "xen:///" to URI components:
  scheme xen
  server (null)
  user (null)
  port 0
  path /

2012-09-19 11:37:56.043+0000: 14927: debug : do_open:1174 : trying driver 0 (Test) ...
2012-09-19 11:37:56.043+0000: 14927: debug : do_open:1180 : driver 0 Test returned DECLINED
2012-09-19 11:37:56.043+0000: 14927: debug : do_open:1174 : trying driver 1 (OPENVZ) ...
2012-09-19 11:37:56.043+0000: 14927: debug : do_open:1180 : driver 1 OPENVZ returned DECLINED
2012-09-19 11:37:56.043+0000: 14927: debug : do_open:1174 : trying driver 2 (VMWARE) ...
2012-09-19 11:37:56.043+0000: 14927: debug : do_open:1180 : driver 2 VMWARE returned DECLINED
2012-09-19 11:37:56.043+0000: 14927: debug : do_open:1174 : trying driver 3 (VBOX) ...
2012-09-19 11:37:56.043+0000: 14927: debug : do_open:1180 : driver 3 VBOX returned DECLINED
2012-09-19 11:37:56.043+0000: 14927: debug : do_open:1174 : trying driver 4 (remote) ...
2012-09-19 11:37:56.043+0000: 14927: debug : do_open:1180 : driver 4 remote returned DECLINED
2012-09-19 11:37:56.043+0000: 14927: debug : do_open:1174 : trying driver 5 (Xen) ...
2012-09-19 11:37:56.043+0000: 14927: debug : xenUnifiedOpen:347 : Trying hypervisor sub-driver
2012-09-19 11:37:56.043+0000: 14927: debug : xenHypervisorInit:2039 : Using new hypervisor call: 40002
2012-09-19 11:37:56.044+0000: 14927: error : xenHypervisorDoV2Sys:1007 : Unable to issue hypervisor ioctl 3166208: Permission denied
2012-09-19 11:37:56.044+0000: 14927: error : xenHypervisorDoV2Sys:1007 : Unable to issue hypervisor ioctl 3166208: Permission denied
2012-09-19 11:37:56.044+0000: 14919: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
2012-09-19 11:37:56.044+0000: 14919: debug : virEventPollCleanupHandles:549 : Cleanup 11
2012-09-19 11:37:56.044+0000: 14927: error : xenHypervisorDoV2Sys:1007 : Unable to issue hypervisor ioctl 3166208: Permission denied
2012-09-19 11:37:56.044+0000: 14919: debug : virEventRunDefaultImpl:244 : running default event implementation
2012-09-19 11:37:56.044+0000: 14919: debug : virEventPollCleanupTimeouts:501 : Cleanup 2
2012-09-19 11:37:56.044+0000: 14919: debug : virEventPollCleanupHandles:549 : Cleanup 11
2012-09-19 11:37:56.044+0000: 14927: error : xenHypervisorDoV2Sys:1007 : Unable to issue hypervisor ioctl 3166208: Permission denied
2012-09-19 11:37:56.044+0000: 14919: debug : virEventPollMakePollFDs:378 : Prepare n=0 w=1, f=6 e=1 d=0
2012-09-19 11:37:56.044+0000: 14919: debug : virEventPollMakePollFDs:378 : Prepare n=1 w=2, f=8 e=1 d=0
2012-09-19 11:37:56.044+0000: 14919: debug : virEventPollMakePollFDs:378 : Prepare n=2 w=3, f=10 e=1 d=0
2012-09-19 11:37:56.044+0000: 14919: debug : virEventPollMakePollFDs:378 : Prepare n=3 w=4, f=11 e=1 d=0
2012-09-19 11:37:56.044+0000: 14919: debug : virEventPollMakePollFDs:378 : Prepare n=4 w=5, f=12 e=1 d=0
2012-09-19 11:37:56.044+0000: 14919: debug : virEventPollMakePollFDs:378 : Prepare n=5 w=6, f=14 e=1 d=0
2012-09-19 11:37:56.044+0000: 14919: debug : virEventPollMakePollFDs:378 : Prepare n=6 w=7, f=13 e=0 d=0
2012-09-19 11:37:56.044+0000: 14927: error : xenHypervisorDoV2Sys:1007 : Unable to issue hypervisor ioctl 3166208: Permission denied
2012-09-19 11:37:56.044+0000: 14919: debug : virEventPollMakePollFDs:378 : Prepare n=7 w=8, f=13 e=1 d=0
2012-09-19 11:37:56.044+0000: 14919: debug : virEventPollMakePollFDs:378 : Prepare n=8 w=9, f=15 e=1 d=0
2012-09-19 11:37:56.044+0000: 14919: debug : virEventPollMakePollFDs:378 : Prepare n=9 w=10, f=16 e=1 d=0
2012-09-19 11:37:56.044+0000: 14919: debug : virEventPollMakePollFDs:378 : Prepare n=10 w=11, f=17 e=1 d=0
2012-09-19 11:37:56.044+0000: 14919: debug : virEventPollCalculateTimeout:320 : Calculate expiry of 2 timers
2012-09-19 11:37:56.044+0000: 14919: debug : virEventPollCalculateTimeout:326 : Got a timeout scheduled for 1348054681042
2012-09-19 11:37:56.044+0000: 14927: error : xenHypervisorDoV2Sys:1007 : Unable to issue hypervisor ioctl 3166208: Permission denied
2012-09-19 11:37:56.045+0000: 14919: debug : virEventPollCalculateTimeout:346 : Timeout at 1348054681042 due in 4997 ms
2012-09-19 11:37:56.045+0000: 14919: debug : virEventPollRunOnce:614 : EVENT_POLL_RUN: nhandles=10 timeout=4997
2012-09-19 11:37:56.045+0000: 14927: error : xenHypervisorDoV1Op:967 : Unable to issue hypervisor ioctl 3166208: Function not implemented
2012-09-19 11:37:56.045+0000: 14927: error : xenHypervisorInit:2174 : Unable to issue hypervisor ioctl 3166208: Function not implemented
2012-09-19 11:37:56.045+0000: 14927: debug : xenHypervisorInit:2175 : Failed to find any Xen hypervisor method
2012-09-19 11:37:56.045+0000: 14927: debug : virFileClose:72 : Closed fd 18
2012-09-19 11:37:56.045+0000: 14927: debug : xenUnifiedOpen:411 : Failed to activate a mandatory sub-driver
2012-09-19 11:37:56.045+0000: 14927: debug : do_open:1180 : driver 5 Xen returned ERROR

reassign 688101 libvirt

On Wed, Sep 19, 2012 at 01:34:51PM +0200, Michal Suchanek wrote:
[..snip..]

This is a problem between xen and libvirt. You should be able to
reproduce this with virsh to debug this easier. I can't seem to find you
xen version but would recommend to use the libvirt version from
experimental. I don't have any xen systems do debug this around here.
Cheers,
 -- Guido

Can you run virsh as root succesfully? If so you're lacking the
necessary permission to run as regular user.
 -- Guido

Excerpts from Guido Günther's message of Wed Sep 19 16:57:37 +0200 2012:

This is what I am using:

Desired=Unknown/Install/Remove/Purge/Hold
| Status=Not/Inst/Conf-files/Unpacked/halF-conf/Half-inst/trig-aWait/Trig-pend
|/ Err?=(none)/Reinst-required (Status,Err: uppercase=bad)
||/ Name                          Version             Architecture        Description
+++-=============================-===================-===================-===============================================================
ii  libvirt0                      0.10.1-2            amd64               library for interfacing with different virtualization systems
ii  xen-hypervisor-4.2-amd64      4.2.0-1             amd64               Xen Hypervisor on AMD64
ii  xen-utils-4.2                 4.2.0-1             amd64               XEN administrative tools
ii  linux-image-3.5-trunk-amd64   3.5.2-1~experimental.1                  Linux 3.5 for 64-bit PCs

How is running virsh supposed to improve that debug output?

It is supposed to work with some xml files of which no sample is provided with
the package so I can run eg. virsh help but not create a domain.

Thanks

Michal