- Users - libvirt List Archives

[libvirt-users] Libvirt fails to build with sasl
by Jonny Schlag 08 Oct '15

08 Oct '15

Description of problem: I try to build a libvirt package for the IPFire project (http://www.ipfire.org/) Without sasl libvirt build fine but with sasl libvirt fails to build with the following message: CC rpc/libvirt_net_rpc_la-virnetsaslcontext.lo In file included from rpc/virnetsaslcontext.h:25, from rpc/virnetsaslcontext.c:26: /usr/include/sasl/sasl.h:230: error: expected declaration specifiers or '...' before 'size_t' Makefile:7023: recipe for target 'rpc/libvirt_net_rpc_la-virnetsaslcontext.lo' failed make[3]: *** [rpc/libvirt_net_rpc_la-virnetsaslcontext.lo] Error 1 make[3]: Leaving directory '/libvirt-1.2.20/src' Makefile:5119: recipe for target 'all' failed make[2]: *** [all] Error 2 make[2]: Leaving directory '/libvirt-1.2.20/src' Makefile:1999: recipe for target 'all-recursive' failed make[1]: *** [all-recursive] Error 1 make[1]: Leaving directory '/libvirt-1.2.20' Makefile:1896: recipe for target 'all' failed make: *** [all] Error 2 Version-Release number of selected component (if applicable): 1.2.20 How reproducible: Build libvirt with: 1. ./configure --prefix=/usr --localstatedir=/var --sysconfdir=/etc --with-openssl --with-sasl --without-uml --without-vbox --without-lxc --without-esx --without-vmware --without-openvz --without-firewalld --without-storage-scsi --without-storage-mpath --without-avahi --with-test-suite -without-dbus --without-storage-lvm --without-pm-utils 2. make 3. the error occurs Additional info: Cyrus sasl (2.1.26) is build with ./configure --prefix=/usr --sysconfdir=/etc \ --with-dbpath=/var/lib/sasl/sasldb2 \ --with-saslauthd=/var/run/saslauthd \ --with-des=no --with-rc4=no I attached the whole terminal output. Sincerely Jonatan Schlag PS.: Sorry for my bad english, i hope you could understand me.

2 2

[libvirt-users] libvirt 1.19: could not open drive file (permission denied)
by crequill 07 Oct '15

07 Oct '15

Hi, With libvrit 1.18 all is working fine: I can open my win7 VM on Archlinux without problem. But I cannot use it with libvirt 1.19: it could not open drive file (permission denied). In /etc/libvirt/qemu.conf, I have: - user: root - group: root And the drive file has root:root as owner. Why this configuration is running with libvirt 1.18 and not with libvirt 1.19 ? Many thanks for your reply. Christophe

2 6

[libvirt-users] Placing tls client certificates
by Dmitry Skorobogatiy 06 Oct '15

06 Oct '15

After reading you guide about configuring qemu + tls (http://wiki.libvirt.org/page/TLSSetup) i face with fact, that i can't move client certificate. After all, it's useful to configure all pki hosts similar: place certs in, for example, /etc/pki/libvirt/cert.pem and use it as client certs in one case and as server cert in other case. So, the question is: how can i configure location of the client certificate files? One way is to set variables in /etc/libvirt/libvirtd.conf on using client cert location. But it can be cause of confusing later. PS: Sorry for my English. -- Best regards, Skorobogatiy Dmitry

1 0

[libvirt-users] Storage statistics
by Giuseppe Civitella 06 Oct '15

06 Oct '15

Hi all, I'd like to get a few storage statistics from a running vm. I'd like to know read and write data about QCOW2 devices and about RBD volumes. I did try to execute a command similar to this: virsh qemu-monitor-command vm-vnc '{ "execute": "query-block"}' as described here: https://www.berrange.com/posts/2011/12/19/using-command-line-arg-monitor-co… and implemented here: https://github.com/cernceph/ceph-scripts/blob/master/tools/virsh-throttle-r… But I do not get any live data. As example, I get this values about RBD volumes whether I do or I don't read data from the volume inside the vm: { "device": "drive-virtio-disk1", "inserted": { "backing_file_depth": 0, "bps": 0, "bps_rd": 0, "bps_wr": 0, "drv": "raw", "encrypted": false, "encryption_key_missing": false, "file": "rbd:volumes/volume-791f56d7-d115-4889-ba6c-85f7795b84db:id=cinder:key=AQCxLlxTYI2gHBAAWztETyuM1Nkgu6+0ANkl1w==:auth_supported=cephx\\;none:mon_host=10.19.24.127\\:6789\\;10.19.24.128\\:6789\\;10.19.24.129\\:6789", "image": { "cluster-size": 4194304, "dirty-flag": false, "filename": "rbd:volumes/volume-791f56d7-d115-4889-ba6c-85f7795b84db:id=cinder:key=AQCxLlxTYI2gHBAAWztETyuM1Nkgu6+0ANkl1w==:auth_supported=cephx\\;none:mon_host=10.19.24.127\\:6789\\;10.19.24.128\\:6789\\;10.19.24.129\\:6789", "format": "raw", "virtual-size": 5368709120 }, "iops": 0, "iops_rd": 50, "iops_rd_max": 5, "iops_wr": 50, "iops_wr_max": 5, "ro": false }, "io-status": "ok", "locked": false, "removable": false, "type": "unknown" } ] Do I need to enable the statistics collection in a specific way? Is there a better way of doing this? My system is an Ubuntu 14.04.2 with libvirtd (libvirt) 1.2.2, kernel 3.13.0-55, ceph version 0.80.9. Thanks Giuseppe -- Giuseppe Civitella gcivitella(a)entermail.it

1 0

[libvirt-users] hvc - hypervisor virtual console & virsh
by poma 03 Oct '15

03 Oct '15

Hi Fi, cmdline: console=hvc0 systemd.wants=getty@hvc0 Connect via virt-manager - View / Text Consoles / Text Console no.: Fedora 24 (Rawhide) Kernel 4.3.0-0.rc3.git2.4.fc24.x86_64 on an x86_64 (hvc0) localhost login: root Last login: Fri Oct 2 17:28:36 on hvc0 # tty /dev/hvc0 # systemctl status getty(a)hvc0.service ● getty(a)hvc0.service - Getty on hvc0 Loaded: loaded (/usr/lib/systemd/system/getty@.service; enabled; vendor preset: enabled) Active: active (running) since Fri 2015-10-02 17:28:39 EDT; 26s ago Docs: man:agetty(8) man:systemd-getty-generator(8) http://0pointer.de/blog/projects/serial-console.html Main PID: 2034 (login) CGroup: /system.slice/system-getty.slice/getty(a)hvc0.service ‣ 2034 login -- root Oct 02 17:28:39 localhost systemd[1]: Started Getty on hvc0. It obviously works. Connect via virsh: # virsh console <domain> console1 Connected to domain <domain> Escape character is ^] error: operation failed: Active console session exists for this domain # virsh console --devname console1 <domain> Connected to domain <domain> Escape character is ^] error: operation failed: Active console session exists for this domain Obviously does not work. Is it possible to connect hvc via virsh, if domain/guest is started with the virt-manager? Is it possible at all, to connect hypervisor virtual console via virsh?

1 1

[libvirt-users] virsh with OSX and virtualbox
by Ben Greene 02 Oct '15

02 Oct '15

I have freshly installed Virtualbox (5.04) and have installed libvirt (1.2.19) using brew. When i attempt to run virsh it does not work with the following outputs. # virsh -c vbox:///session error: failed to connect to the hypervisor error: internal error: unable to initialize VirtualBox driver API Any pointers on what to try next or where to investigate to get more info. Has anyone got virsh working successfully with OSX and Virtualbox. I also ran the same command with more logging: Foyil:~ bengreene$ LIBVIRT_DEBUG=1 virsh -c vbox:///session 2015-10-02 08:52:11.874+0000: 18446744073709551615: info : libvirt version: 1.2.19 2015-10-02 08:52:11.874+0000: 18446744073709551615: debug : virGlobalInit:389 : register drivers 2015-10-02 08:52:11.874+0000: 18446744073709551615: debug : virRegisterConnectDriver:691 : driver=0x1007121d8 name=Test 2015-10-02 08:52:11.874+0000: 18446744073709551615: debug : virRegisterConnectDriver:702 : registering Test as driver 0 2015-10-02 08:52:11.874+0000: 18446744073709551615: debug : virRegisterConnectDriver:691 : driver=0x100713e00 name=VMWARE 2015-10-02 08:52:11.874+0000: 18446744073709551615: debug : virRegisterConnectDriver:702 : registering VMWARE as driver 1 2015-10-02 08:52:11.874+0000: 18446744073709551615: debug : virRegisterConnectDriver:691 : driver=0x1007144f8 name=ESX 2015-10-02 08:52:11.874+0000: 18446744073709551615: debug : virRegisterConnectDriver:702 : registering ESX as driver 2 2015-10-02 08:52:11.874+0000: 18446744073709551615: debug : virRegisterConnectDriver:691 : driver=0x100712c10 name=remote 2015-10-02 08:52:11.874+0000: 18446744073709551615: debug : virRegisterConnectDriver:702 : registering remote as driver 3 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : virEventRegisterDefaultImpl:261 : registering default event implementation 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : virEventPollAddHandle:115 : Used 0 handle slots, adding at least 10 more 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : virEventPollInterruptLocked:723 : Skip interrupt, 0 0 2015-10-02 08:52:11.876+0000: 18446744073709551615: info : virEventPollAddHandle:140 : EVENT_POLL_ADD_HANDLE: watch=1 fd=4 events=1 cb=0x10050c74e opaque=0x0 ff=0x0 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : virEventRegisterImpl:231 : addHandle=0x10050afa3 updateHandle=0x10050b296 removeHandle=0x10050b3da addTimeout=0x10050b528 updateTimeout=0x10050b6f6 removeTimeout=0x10050b879 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : virEventPollAddTimeout:230 : Used 0 timeout slots, adding at least 10 more 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : virEventPollInterruptLocked:723 : Skip interrupt, 0 0 2015-10-02 08:52:11.876+0000: 18446744073709551615: info : virEventPollAddTimeout:253 : EVENT_POLL_ADD_TIMEOUT: timer=1 frequency=-1 cb=0x100490c0c opaque=0x7fff5f774900 ff=0x0 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : virConnectOpenAuth:1339 : name=vbox:///session, auth=0x100711ff0, flags=0 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : virThreadJobSet:99 : Thread 18446744073709551615 is now running job vshEventLoop 2015-10-02 08:52:11.876+0000: 18446744073709551615: info : virObjectNew:202 : OBJECT_NEW: obj=0x7f9cda600b10 classname=virConnect 2015-10-02 08:52:11.876+0000: 18446744073709551615: info : virObjectNew:202 : OBJECT_NEW: obj=0x7f9cda600c20 classname=virConnectCloseCallbackData 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : virEventRunDefaultImpl:305 : running default event implementation 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : virEventPollCleanupTimeouts:526 : Cleanup 1 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : virEventPollCleanupHandles:575 : Cleanup 1 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : virEventPollMakePollFDs:401 : Prepare n=0 w=1, f=4 e=1 d=0 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : virEventPollCalculateTimeout:338 : Calculate expiry of 1 timers 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : virEventPollCalculateTimeout:371 : No timeout is pending 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : do_open:1132 : name "vbox:///session" to URI components: scheme vbox server <null> user <null> port -1 path /session 2015-10-02 08:52:11.876+0000: 18446744073709551615: info : virEventPollRunOnce:641 : EVENT_POLL_RUN: nhandles=1 timeout=-1 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : do_open:1179 : trying driver 0 (Test) ... 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : do_open:1194 : driver 0 Test returned DECLINED 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : do_open:1179 : trying driver 1 (VMWARE) ... 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : do_open:1194 : driver 1 VMWARE returned DECLINED 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : do_open:1179 : trying driver 2 (ESX) ... 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : do_open:1194 : driver 2 ESX returned DECLINED 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : do_open:1179 : trying driver 3 (remote) ... 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : remoteConnectOpen:1176 : Auto-spawn user daemon instance 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : doRemoteOpen:792 : proceeding with name = vbox:///session 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : doRemoteOpen:801 : Connecting with transport 1 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : doRemoteOpen:893 : Proceeding with sockname /Users/bengreene/.cache/libvirt/libvirt-sock 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : virFileFindResourceFull:1692 : Resolved 'libvirtd' to '/usr/local/Cellar/libvirt/1.2.19/sbin/libvirtd' 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : virFileMakePathHelper:2571 : path=/Users/bengreene/.cache/libvirt mode=0700 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : virNetSocketNew:226 : localAddr=0x7fff5f774330 remoteAddr=0x7fff5f7742a0 fd=7 errfd=-1 pid=0 2015-10-02 08:52:11.876+0000: 18446744073709551615: info : virObjectNew:202 : OBJECT_NEW: obj=0x7f9cda700730 classname=virNetSocket 2015-10-02 08:52:11.876+0000: 18446744073709551615: info : virNetSocketNew:277 : RPC_SOCKET_NEW: sock=0x7f9cda700730 fd=7 errfd=-1 pid=0 localAddr=127.0.0.1;0, remoteAddr=127.0.0.1;0 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : virFileClose:103 : Closed fd 6 2015-10-02 08:52:11.876+0000: 18446744073709551615: info : virObjectNew:202 : OBJECT_NEW: obj=0x7f9cda7005c0 classname=virNetClient 2015-10-02 08:52:11.876+0000: 18446744073709551615: info : virNetClientNew:327 : RPC_CLIENT_NEW: client=0x7f9cda7005c0 sock=0x7f9cda700730 2015-10-02 08:52:11.876+0000: 18446744073709551615: info : virObjectRef:296 : OBJECT_REF: obj=0x7f9cda7005c0 2015-10-02 08:52:11.876+0000: 18446744073709551615: info : virObjectRef:296 : OBJECT_REF: obj=0x7f9cda700730 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : virEventPollInterruptLocked:727 : Interrupting 2015-10-02 08:52:11.876+0000: 18446744073709551615: info : virEventPollAddHandle:140 : EVENT_POLL_ADD_HANDLE: watch=2 fd=7 events=1 cb=0x10062471b opaque=0x7f9cda700730 ff=0x100624768 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : virKeepAliveNew:199 : client=0x7f9cda7005c0, interval=-1, count=0 2015-10-02 08:52:11.876+0000: 18446744073709551615: info : virObjectNew:202 : OBJECT_NEW: obj=0x7f9cda700910 classname=virKeepAlive 2015-10-02 08:52:11.876+0000: 18446744073709551615: debug : virEventPollRunOnce:651 : Poll got 1 event(s) 2015-10-02 08:52:11.877+0000: 18446744073709551615: info : virKeepAliveNew:218 : RPC_KEEPALIVE_NEW: ka=0x7f9cda700910 client=0x7f9cda7005c0 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollDispatchTimeouts:433 : Dispatch 1 2015-10-02 08:52:11.877+0000: 18446744073709551615: info : virObjectRef:296 : OBJECT_REF: obj=0x7f9cda7005c0 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollDispatchHandles:479 : Dispatch 1 2015-10-02 08:52:11.877+0000: 18446744073709551615: info : virObjectRef:296 : OBJECT_REF: obj=0x7f9cda600c20 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollDispatchHandles:493 : i=0 w=1 2015-10-02 08:52:11.877+0000: 18446744073709551615: info : virObjectNew:202 : OBJECT_NEW: obj=0x7f9cda600ed0 classname=virNetClientProgram 2015-10-02 08:52:11.877+0000: 18446744073709551615: info : virEventPollDispatchHandles:507 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2015-10-02 08:52:11.877+0000: 18446744073709551615: info : virObjectNew:202 : OBJECT_NEW: obj=0x7f9cda600e00 classname=virNetClientProgram 2015-10-02 08:52:11.877+0000: 18446744073709551615: info : virObjectNew:202 : OBJECT_NEW: obj=0x7f9cda600e30 classname=virNetClientProgram 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollCleanupTimeouts:526 : Cleanup 1 2015-10-02 08:52:11.877+0000: 18446744073709551615: info : virObjectRef:296 : OBJECT_REF: obj=0x7f9cda600ed0 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollCleanupHandles:575 : Cleanup 2 2015-10-02 08:52:11.877+0000: 18446744073709551615: info : virObjectRef:296 : OBJECT_REF: obj=0x7f9cda600e00 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventRunDefaultImpl:305 : running default event implementation 2015-10-02 08:52:11.877+0000: 18446744073709551615: info : virObjectRef:296 : OBJECT_REF: obj=0x7f9cda600e30 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollCleanupTimeouts:526 : Cleanup 1 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : doRemoteOpen:1010 : Trying authentication 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollCleanupHandles:575 : Cleanup 2 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollMakePollFDs:401 : Prepare n=0 w=1, f=4 e=1 d=0 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virNetMessageNew:46 : msg=0x7f9cda600d10 tracked=0 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollMakePollFDs:401 : Prepare n=1 w=2, f=7 e=1 d=0 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollCalculateTimeout:338 : Calculate expiry of 1 timers 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollCalculateTimeout:371 : No timeout is pending 2015-10-02 08:52:11.877+0000: 18446744073709551615: info : virEventPollRunOnce:641 : EVENT_POLL_RUN: nhandles=2 timeout=-1 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virNetMessageEncodePayload:376 : Encode length as 28 2015-10-02 08:52:11.877+0000: 18446744073709551615: info : virNetClientSendInternal:1950 : RPC_CLIENT_MSG_TX_QUEUE: client=0x7f9cda7005c0 len=28 prog=536903814 vers=1 proc=66 type=0 status=0 serial=0 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virNetClientCallNew:1903 : New call 0x7f9cda600f00: msg=0x7f9cda600d10, expectReply=1, nonBlock=0 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virNetClientIO:1712 : Outgoing message prog=536903814 version=1 serial=0 proc=66 type=0 length=28 dispatch=0x0 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virNetClientIO:1771 : We have the buck head=0x7f9cda600f00 call=0x7f9cda600f00 2015-10-02 08:52:11.877+0000: 18446744073709551615: info : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollInterruptLocked:727 : Interrupting 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollRunOnce:651 : Poll got 1 event(s) 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollDispatchTimeouts:433 : Dispatch 1 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollDispatchHandles:479 : Dispatch 2 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollDispatchHandles:493 : i=0 w=1 2015-10-02 08:52:11.877+0000: 18446744073709551615: info : virEventPollDispatchHandles:507 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollCleanupTimeouts:526 : Cleanup 1 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollCleanupHandles:575 : Cleanup 2 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventRunDefaultImpl:305 : running default event implementation 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollCleanupTimeouts:526 : Cleanup 1 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollCleanupHandles:575 : Cleanup 2 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollMakePollFDs:401 : Prepare n=0 w=1, f=4 e=1 d=0 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollMakePollFDs:401 : Prepare n=1 w=2, f=7 e=0 d=0 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollCalculateTimeout:338 : Calculate expiry of 1 timers 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollCalculateTimeout:371 : No timeout is pending 2015-10-02 08:52:11.877+0000: 18446744073709551615: info : virEventPollRunOnce:641 : EVENT_POLL_RUN: nhandles=1 timeout=-1 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virNetMessageDecodeLength:151 : Got length, now need 36 total (32 more) 2015-10-02 08:52:11.877+0000: 18446744073709551615: info : virNetClientCallDispatch:1122 : RPC_CLIENT_MSG_RX: client=0x7f9cda7005c0 len=36 prog=536903814 vers=1 proc=66 type=1 status=0 serial=0 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virKeepAliveCheckMessage:377 : ka=0x7f9cda700910, client=0x7f9cda7005c0, msg=0x7f9cda700640 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virNetMessageClear:57 : msg=0x7f9cda700640 nfds=0 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virNetClientIOEventLoopPassTheBuck:1419 : Giving up the buck 0x7f9cda600f00 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virNetClientIOEventLoopPassTheBuck:1433 : No thread to pass the buck to 2015-10-02 08:52:11.877+0000: 18446744073709551615: info : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollInterruptLocked:727 : Interrupting 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virNetClientIO:1801 : All done with our call head=0x0 call=0x7f9cda600f00 rv=0 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollRunOnce:651 : Poll got 1 event(s) 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollDispatchTimeouts:433 : Dispatch 1 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollDispatchHandles:479 : Dispatch 1 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virNetMessageFree:74 : msg=0x7f9cda600d10 nfds=0 cb=0x0 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollDispatchHandles:493 : i=0 w=1 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virNetMessageNew:46 : msg=0x7f9cda500170 tracked=0 2015-10-02 08:52:11.877+0000: 18446744073709551615: info : virEventPollDispatchHandles:507 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollCleanupTimeouts:526 : Cleanup 1 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virNetMessageEncodePayload:376 : Encode length as 32 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollCleanupHandles:575 : Cleanup 2 2015-10-02 08:52:11.877+0000: 18446744073709551615: info : virNetClientSendInternal:1950 : RPC_CLIENT_MSG_TX_QUEUE: client=0x7f9cda7005c0 len=32 prog=536903814 vers=1 proc=60 type=0 status=0 serial=1 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventRunDefaultImpl:305 : running default event implementation 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virNetClientCallNew:1903 : New call 0x7f9cda500260: msg=0x7f9cda500170, expectReply=1, nonBlock=0 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollCleanupTimeouts:526 : Cleanup 1 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virNetClientIO:1712 : Outgoing message prog=536903814 version=1 serial=1 proc=60 type=0 length=32 dispatch=0x0 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollCleanupHandles:575 : Cleanup 2 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virNetClientIO:1771 : We have the buck head=0x7f9cda500260 call=0x7f9cda500260 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollMakePollFDs:401 : Prepare n=0 w=1, f=4 e=1 d=0 2015-10-02 08:52:11.877+0000: 18446744073709551615: info : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollMakePollFDs:401 : Prepare n=1 w=2, f=7 e=1 d=0 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollCalculateTimeout:338 : Calculate expiry of 1 timers 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollCalculateTimeout:371 : No timeout is pending 2015-10-02 08:52:11.877+0000: 18446744073709551615: info : virEventPollRunOnce:641 : EVENT_POLL_RUN: nhandles=2 timeout=-1 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollInterruptLocked:727 : Interrupting 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollRunOnce:651 : Poll got 1 event(s) 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollDispatchTimeouts:433 : Dispatch 1 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollDispatchHandles:479 : Dispatch 2 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollDispatchHandles:493 : i=0 w=1 2015-10-02 08:52:11.877+0000: 18446744073709551615: info : virEventPollDispatchHandles:507 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollCleanupTimeouts:526 : Cleanup 1 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollCleanupHandles:575 : Cleanup 2 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventRunDefaultImpl:305 : running default event implementation 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollCleanupTimeouts:526 : Cleanup 1 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollCleanupHandles:575 : Cleanup 2 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollMakePollFDs:401 : Prepare n=0 w=1, f=4 e=1 d=0 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollMakePollFDs:401 : Prepare n=1 w=2, f=7 e=0 d=0 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollCalculateTimeout:338 : Calculate expiry of 1 timers 2015-10-02 08:52:11.877+0000: 18446744073709551615: debug : virEventPollCalculateTimeout:371 : No timeout is pending 2015-10-02 08:52:11.877+0000: 18446744073709551615: info : virEventPollRunOnce:641 : EVENT_POLL_RUN: nhandles=1 timeout=-1 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virNetMessageDecodeLength:151 : Got length, now need 32 total (28 more) 2015-10-02 08:52:11.878+0000: 18446744073709551615: info : virNetClientCallDispatch:1122 : RPC_CLIENT_MSG_RX: client=0x7f9cda7005c0 len=32 prog=536903814 vers=1 proc=60 type=1 status=0 serial=1 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virKeepAliveCheckMessage:377 : ka=0x7f9cda700910, client=0x7f9cda7005c0, msg=0x7f9cda700640 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virNetMessageClear:57 : msg=0x7f9cda700640 nfds=0 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virNetClientIOEventLoopPassTheBuck:1419 : Giving up the buck 0x7f9cda500260 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virNetClientIOEventLoopPassTheBuck:1433 : No thread to pass the buck to 2015-10-02 08:52:11.878+0000: 18446744073709551615: info : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollInterruptLocked:727 : Interrupting 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virNetClientIO:1801 : All done with our call head=0x0 call=0x7f9cda500260 rv=0 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virNetMessageFree:74 : msg=0x7f9cda500170 nfds=0 cb=0x0 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : doRemoteOpen:1036 : Trying to open URI vbox:///session 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollRunOnce:651 : Poll got 1 event(s) 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virNetMessageNew:46 : msg=0x7f9cda500150 tracked=0 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollDispatchTimeouts:433 : Dispatch 1 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollDispatchHandles:479 : Dispatch 1 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virNetMessageEncodePayload:376 : Encode length as 56 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollDispatchHandles:493 : i=0 w=1 2015-10-02 08:52:11.878+0000: 18446744073709551615: info : virNetClientSendInternal:1950 : RPC_CLIENT_MSG_TX_QUEUE: client=0x7f9cda7005c0 len=56 prog=536903814 vers=1 proc=1 type=0 status=0 serial=2 2015-10-02 08:52:11.878+0000: 18446744073709551615: info : virEventPollDispatchHandles:507 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virNetClientCallNew:1903 : New call 0x7f9cda700440: msg=0x7f9cda500150, expectReply=1, nonBlock=0 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollCleanupTimeouts:526 : Cleanup 1 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virNetClientIO:1712 : Outgoing message prog=536903814 version=1 serial=2 proc=1 type=0 length=56 dispatch=0x0 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollCleanupHandles:575 : Cleanup 2 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virNetClientIO:1771 : We have the buck head=0x7f9cda700440 call=0x7f9cda700440 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventRunDefaultImpl:305 : running default event implementation 2015-10-02 08:52:11.878+0000: 18446744073709551615: info : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=0 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollCleanupTimeouts:526 : Cleanup 1 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollCleanupHandles:575 : Cleanup 2 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollMakePollFDs:401 : Prepare n=0 w=1, f=4 e=1 d=0 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollMakePollFDs:401 : Prepare n=1 w=2, f=7 e=1 d=0 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollCalculateTimeout:338 : Calculate expiry of 1 timers 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollCalculateTimeout:371 : No timeout is pending 2015-10-02 08:52:11.878+0000: 18446744073709551615: info : virEventPollRunOnce:641 : EVENT_POLL_RUN: nhandles=2 timeout=-1 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollInterruptLocked:727 : Interrupting 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollRunOnce:651 : Poll got 1 event(s) 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollDispatchTimeouts:433 : Dispatch 1 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollDispatchHandles:479 : Dispatch 2 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollDispatchHandles:493 : i=0 w=1 2015-10-02 08:52:11.878+0000: 18446744073709551615: info : virEventPollDispatchHandles:507 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollCleanupTimeouts:526 : Cleanup 1 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollCleanupHandles:575 : Cleanup 2 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventRunDefaultImpl:305 : running default event implementation 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollCleanupTimeouts:526 : Cleanup 1 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollCleanupHandles:575 : Cleanup 2 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollMakePollFDs:401 : Prepare n=0 w=1, f=4 e=1 d=0 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollMakePollFDs:401 : Prepare n=1 w=2, f=7 e=0 d=0 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollCalculateTimeout:338 : Calculate expiry of 1 timers 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollCalculateTimeout:371 : No timeout is pending 2015-10-02 08:52:11.878+0000: 18446744073709551615: info : virEventPollRunOnce:641 : EVENT_POLL_RUN: nhandles=1 timeout=-1 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virNetMessageDecodeLength:151 : Got length, now need 208 total (204 more) 2015-10-02 08:52:11.878+0000: 18446744073709551615: info : virNetClientCallDispatch:1122 : RPC_CLIENT_MSG_RX: client=0x7f9cda7005c0 len=208 prog=536903814 vers=1 proc=1 type=1 status=1 serial=2 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virKeepAliveCheckMessage:377 : ka=0x7f9cda700910, client=0x7f9cda7005c0, msg=0x7f9cda700640 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virNetMessageClear:57 : msg=0x7f9cda700640 nfds=0 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virNetClientIOEventLoopPassTheBuck:1419 : Giving up the buck 0x7f9cda700440 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virNetClientIOEventLoopPassTheBuck:1433 : No thread to pass the buck to 2015-10-02 08:52:11.878+0000: 18446744073709551615: info : virEventPollUpdateHandle:152 : EVENT_POLL_UPDATE_HANDLE: watch=2 events=1 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollInterruptLocked:727 : Interrupting 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virNetClientIO:1801 : All done with our call head=0x0 call=0x7f9cda700440 rv=0 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollRunOnce:651 : Poll got 1 event(s) 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollDispatchTimeouts:433 : Dispatch 1 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virNetMessageFree:74 : msg=0x7f9cda500150 nfds=0 cb=0x0 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollDispatchHandles:479 : Dispatch 1 2015-10-02 08:52:11.878+0000: 18446744073709551615: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9cda600ed0 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollDispatchHandles:493 : i=0 w=1 2015-10-02 08:52:11.878+0000: 18446744073709551615: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9cda600e00 2015-10-02 08:52:11.878+0000: 18446744073709551615: info : virEventPollDispatchHandles:507 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2015-10-02 08:52:11.878+0000: 18446744073709551615: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9cda600e30 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollCleanupTimeouts:526 : Cleanup 1 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virNetClientCloseInternal:695 : client=0x7f9cda7005c0 wantclose=0 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollCleanupHandles:575 : Cleanup 2 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virNetClientMarkClose:638 : client=0x7f9cda7005c0, reason=3 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventRunDefaultImpl:305 : running default event implementation 2015-10-02 08:52:11.878+0000: 18446744073709551615: info : virEventPollRemoveHandle:186 : EVENT_POLL_REMOVE_HANDLE: watch=2 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollCleanupTimeouts:526 : Cleanup 1 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollCleanupHandles:575 : Cleanup 2 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollMakePollFDs:401 : Prepare n=0 w=1, f=4 e=1 d=0 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollMakePollFDs:401 : Prepare n=1 w=2, f=7 e=1 d=0 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollCalculateTimeout:338 : Calculate expiry of 1 timers 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollCalculateTimeout:371 : No timeout is pending 2015-10-02 08:52:11.878+0000: 18446744073709551615: info : virEventPollRunOnce:641 : EVENT_POLL_RUN: nhandles=2 timeout=-1 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollRemoveHandle:199 : mark delete 1 7 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollInterruptLocked:727 : Interrupting 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virNetClientIOEventLoopPassTheBuck:1419 : Giving up the buck 0x0 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollRunOnce:651 : Poll got 1 event(s) 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virNetClientIOEventLoopPassTheBuck:1433 : No thread to pass the buck to 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollDispatchTimeouts:433 : Dispatch 1 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virNetClientCloseLocked:654 : client=0x7f9cda7005c0, sock=0x7f9cda700730, reason=3 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollDispatchHandles:479 : Dispatch 2 2015-10-02 08:52:11.878+0000: 18446744073709551615: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9cda700730 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollDispatchHandles:493 : i=0 w=1 2015-10-02 08:52:11.878+0000: 18446744073709551615: info : virObjectRef:296 : OBJECT_REF: obj=0x7f9cda7005c0 2015-10-02 08:52:11.878+0000: 18446744073709551615: info : virEventPollDispatchHandles:507 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2015-10-02 08:52:11.878+0000: 18446744073709551615: info : virKeepAliveStop:310 : RPC_KEEPALIVE_STOP: ka=0x7f9cda700910 client=0x7f9cda7005c0 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollDispatchHandles:493 : i=1 w=2 2015-10-02 08:52:11.878+0000: 18446744073709551615: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9cda700910 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollDispatchHandles:496 : Skip deleted n=1 w=2 f=7 2015-10-02 08:52:11.878+0000: 18446744073709551615: info : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7f9cda700910 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollCleanupTimeouts:526 : Cleanup 1 2015-10-02 08:52:11.878+0000: 18446744073709551615: info : virKeepAliveDispose:230 : RPC_KEEPALIVE_DISPOSE: ka=0x7f9cda700910 2015-10-02 08:52:11.878+0000: 18446744073709551615: debug : virEventPollCleanupHandles:575 : Cleanup 2 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9cda7005c0 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virEventPollCleanupHandles:588 : EVENT_POLL_PURGE_HANDLE: watch=2 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9cda7005c0 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9cda700730 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9cda7005c0 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7f9cda700730 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9cda7005c0 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virNetSocketDispose:1157 : RPC_SOCKET_DISPOSE: sock=0x7f9cda700730 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7f9cda7005c0 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virNetClientDispose:606 : RPC_CLIENT_DISPOSE: client=0x7f9cda7005c0 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9cda600c20 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9cda600ed0 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7f9cda600ed0 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9cda600e00 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7f9cda600e00 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9cda600e30 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7f9cda600e30 2015-10-02 08:52:11.879+0000: 18446744073709551615: debug : virFileClose:103 : Closed fd 8 2015-10-02 08:52:11.879+0000: 18446744073709551615: debug : virFileClose:103 : Closed fd 6 2015-10-02 08:52:11.879+0000: 18446744073709551615: debug : virNetMessageClear:57 : msg=0x7f9cda700640 nfds=0 2015-10-02 08:52:11.879+0000: 18446744073709551615: debug : do_open:1194 : driver 3 remote returned ERROR 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9cda600b10 2015-10-02 08:52:11.879+0000: 18446744073709551615: debug : virFileClose:103 : Closed fd 7 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7f9cda600b10 2015-10-02 08:52:11.879+0000: 18446744073709551615: debug : virEventRunDefaultImpl:305 : running default event implementation 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virObjectUnref:259 : OBJECT_UNREF: obj=0x7f9cda600c20 2015-10-02 08:52:11.879+0000: 18446744073709551615: debug : virEventPollCleanupTimeouts:526 : Cleanup 1 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virObjectUnref:261 : OBJECT_DISPOSE: obj=0x7f9cda600c20 2015-10-02 08:52:11.879+0000: 18446744073709551615: debug : virEventPollCleanupHandles:575 : Cleanup 1 2015-10-02 08:52:11.879+0000: 18446744073709551615: debug : virEventPollMakePollFDs:401 : Prepare n=0 w=1, f=4 e=1 d=0 2015-10-02 08:52:11.879+0000: 18446744073709551615: debug : virEventPollCalculateTimeout:338 : Calculate expiry of 1 timers 2015-10-02 08:52:11.879+0000: 18446744073709551615: debug : virEventPollCalculateTimeout:371 : No timeout is pending error: 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virEventPollRunOnce:641 : EVENT_POLL_RUN: nhandles=1 timeout=-1 failed to connect to the hypervisor error: internal error: unable to initialize VirtualBox driver API 2015-10-02 08:52:11.879+0000: 18446744073709551615: debug : virFileMakePathHelper:2571 : path=/Users/bengreene/.cache/libvirt/virsh mode=0755 2015-10-02 08:52:11.879+0000: 18446744073709551615: debug : virEventPollInterruptLocked:727 : Interrupting 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virEventPollAddTimeout:253 : EVENT_POLL_ADD_TIMEOUT: timer=2 frequency=0 cb=0x100493620 opaque=0x0 ff=0x0 2015-10-02 08:52:11.879+0000: 18446744073709551615: debug : virEventPollRunOnce:651 : Poll got 1 event(s) 2015-10-02 08:52:11.879+0000: 18446744073709551615: debug : virEventPollDispatchTimeouts:433 : Dispatch 2 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virEventPollDispatchTimeouts:456 : EVENT_POLL_DISPATCH_TIMEOUT: timer=2 2015-10-02 08:52:11.879+0000: 18446744073709551615: debug : virEventPollDispatchHandles:479 : Dispatch 1 2015-10-02 08:52:11.879+0000: 18446744073709551615: debug : virEventPollDispatchHandles:493 : i=0 w=1 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virEventPollDispatchHandles:507 : EVENT_POLL_DISPATCH_HANDLE: watch=1 events=1 2015-10-02 08:52:11.879+0000: 18446744073709551615: debug : virEventPollCleanupTimeouts:526 : Cleanup 2 2015-10-02 08:52:11.879+0000: 18446744073709551615: debug : virEventPollCleanupHandles:575 : Cleanup 1 2015-10-02 08:52:11.879+0000: 18446744073709551615: debug : virThreadJobClear:124 : Thread 18446744073709551615 finished job vshEventLoop with ret=0 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virEventPollRemoveTimeout:305 : EVENT_POLL_REMOVE_TIMEOUT: timer=2 2015-10-02 08:52:11.879+0000: 18446744073709551615: debug : virEventPollInterruptLocked:723 : Skip interrupt, 0 123145302839296 2015-10-02 08:52:11.879+0000: 18446744073709551615: info : virEventPollRemoveTimeout:305 : EVENT_POLL_REMOVE_TIMEOUT: timer=1 2015-10-02 08:52:11.879+0000: 18446744073709551615: debug : virEventPollInterruptLocked:723 : Skip interrupt, 0 123145302839296 Foyil:~ bengreene$

1 0

[libvirt-users] Time syncing after VM suspend/resume
by Jérôme 29 Sep '15

29 Sep '15

Hi all. When resuming guest after suspend, the time is wrong (because the guest doesn't know it has been suspended). Apparently, ntp running on the guest doesn't solve this. At least on Linux system. I read it works on Windows guests. I don't understand why, so I'd appreciate a link to an explanation, but I understand it is out of scope for this list. The HW clock is correct and the guest agent is setup to use kvm-clock. Using the guest-set-time command does set the clock on the guest according to the host. But this is not triggered automatically on resume. I found discussions about this, even a patch proposal to call guest-set-time on resume, but all of this is one year old and I can't find any newer information. http://serverfault.com/questions/334698/how-to-keep-time-on-resumed-kvm-gue… https://www.redhat.com/archives/libvirt-users/2014-October/msg00009.html https://www.redhat.com/archives/libvir-list/2014-February/msg00520.html Is there any fresher information I have missed ? Thanks. -- Jérôme

5 6

[libvirt-users] VirtualBox + Virsh
by Julian Zhou 29 Sep '15

29 Sep '15

Hi I am trying to add a virtualbox domain to my virsh installation, I get: error: Failed to define domain from Ondria.xml >>https://gist.github.com/jztech101/5aeebb82412c1910dc1f<< error: internal error: unexpected domain type vbox, expecting one of these: qemu, kqemu, kvm, xen. virsh is compiled with VirtualBox support so I'm confused: Virsh -V >> Virsh command line tool of libvirt 1.2.2 See web site athttp://libvirt.org/ Compiled with support for: Hypervisors: QEMU/KVM LXC UML Xen LibXL OpenVZ VMWare VirtualBox Test Networking: Remote Network Bridging Interface netcf Nwfilter VirtualPort Storage: Dir Disk Filesystem SCSI Multipath iSCSI LVM RBD Sheepdog Miscellaneous: Daemon Nodedev AppArmor Secrets Debug Readline Modular. After some insight, I decided to run it like this instead: " virsh -c vbox:///session create Ondria.xml" Now I get: error: failed to connect to the hypervisor error: internal error: unable to initialize VirtualBox driver API So, after further insight, I ran "export VBOX_APP_HOME=/usr/lib/virtualbox" and tried again. Same error as above. (/usr/lib/virtualbox is where VBoxCPCOMC.so lies and I'm told the reason for the above error is it cannot find that file in any "normal" location. Help? It should be noted I installed VirtualBox-5.0 directly from the VBox Repo for Ubuntu 14.04LTS Regards, Julian Zhou

2 2

[libvirt-users] Fw: libvirtd service not starting
by Jonathan Rurka 26 Sep '15

26 Sep '15

On Thursday, September 24, 2015 12:29 PM, Jonathan Rurka <jon.rurka(a)yahoo.com> wrote: Yes the "which libvirtd" and "sudo virt-host-validate" commands return correct values However, I am having a new problem now; "UEFI" is not selectable under firmware: http://i.imgur.com/O4ypOTX.png I do have VT-d enabled in my bios, my processor supports VT-d, and I am booting ubuntu in UEFI mode. The yellow triangle says "Libvirt did not detect any UEFI/OVMF firmware image installed on the host.". Another person instructed me to set the nvram opeion in /etc/libvirt/qemu.conf to where I have my OVMF placed (which I downloaded from https://www.kraxel.org/repos/jenkins/edk2/) however it hasn't helped. it is set to: nvram = [ "/usr/share/edk2.git/ovmf-x64/OVMF_pure-efi.fd:/usr/share/edk2.git/ovmf-x64/OVMF_VARS-pure-efi.fd" ] On Thursday, September 24, 2015 3:29 AM, Kashyap Chamarthy <kchamart(a)redhat.com> wrote: On Thu, Sep 24, 2015 at 06:47:43AM +0200, Martin Kletzander wrote: > On Wed, Sep 23, 2015 at 11:00:34PM +0000, Jonathan Rurka wrote: > >Hello, I'm new to using libvirt. After a few days of installing and > >removing libvirt, virt-manager and a few others to get VT-d working > >with a virtual machine, I finally got the latest virt-manager and > >libvirt installed from source to get the most recent > >versions. However, when I start up virt-manager I get a popup saying > >"Unable to connect to libvirt; Verify that the 'libvirtd' deamon is > >running.". When running "service libvirtd start", I get an error > >saying libvirtd.service cannot be found. I can, however, use libvirtd > >from the command line. The service file does not exist > >in /lib/systemd/system/. > > > > Did you probably forget to specify the prefix to install it into? you > can do "which libvirtd" to see that you probably installed it into > /usr/local/sbin. Specifying the prefix should help. > > Getting to your "to get VT-d working", you should check whether > everything is enabled in BIOS. That's usually the case if it's not > working for you. [A small addendum to what Martin says.] Jon, you can use `virt-host-validate` to check the above, if everything is setup correctly, it should look like that: $ sudo 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 LXC: Checking for Linux >= 2.6.26 : PASS [. . .] -- /kashyap

2 1

[libvirt-users] PCI passthrough fails in virsh: iommu group is not viable
by Alex Holst 25 Sep '15

25 Sep '15

I would really appreciate some pointers on what I am doing wrong here. I have a need to run multiple virtual guests which have each their own GPU and some USB controllers passed-through. I am able to run one of the guests like this (assuming vfio stuff has happened elsewhere), but I would prefer to use virsh: kvm -M q35 -m 8192 -cpu host,kvm=off \ -smp 4,sockets=1,cores=4,threads=1 \ -bios /usr/share/seabios/bios.bin -vga none \ -device ioh3420,bus=pcie.0,addr=1c.0,multifunction=on,port=1,chassis=1,id=root.1 \ -device vfio-pci,host=02:00.0,bus=root.1,addr=00.0,multifunction=on,x-vga=on \ -device vfio-pci,host=02:00.1,bus=root.1,addr=00.1 \ -device vfio-pci,host=00:1d.0,bus=pcie.0 \ -device vfio-pci,host=00:1a.0,bus=pcie.0 \ -nographic -boot menu=on /vm2/foo.img I found the hardware addresses using lspci. When I invoke the same machine with virsh with what I believe are the same addresses, I get: virsh # start foo error: Failed to start domain foo error: internal error: process exited while connecting to monitor: 2015-08-12T18:24:10.651720Z qemu-system-x86_64: -device vfio-pci,host=02:00.0,id=hostdev0,bus=pci.0,addr=0x4: vfio: error, group 18 is not viable, please ensure all devices within the iommu_group are bound to their vfio bus driver. 2015-08-12T18:24:10.651752Z qemu-system-x86_64: -device vfio-pci,host=02:00.0,id=hostdev0,bus=pci.0,addr=0x4: vfio: failed to get group 18 2015-08-12T18:24:10.651766Z qemu-system-x86_64: -device vfio-pci,host=02:00.0,id=hostdev0,bus=pci.0,addr=0x4: Device initialization failed. 2015-08-12T18:24:10.651781Z qemu-system-x86_64: -device vfio-pci,host=02:00.0,id=hostdev0,bus=pci.0,addr=0x4: Device 'vfio-pci' could not be initialized I have included dumpxml output below -- is the hostdev section wrong? <domain type='kvm'> <name>foo</name> <uuid>51f57655-11be-41bf-b925-2e6aef01f9c4</uuid> <memory unit='KiB'>8388608</memory> <currentMemory unit='KiB'>8388608</currentMemory> <vcpu placement='static' current='1'>4</vcpu> <os> <type arch='x86_64' machine='pc-i440fx-utopic'>hvm</type> <bootmenu enable='yes'/> </os> <features> <acpi/> <apic/> <pae/> <hyperv> <relaxed state='on'/> <vapic state='on'/> <spinlocks state='on' retries='8191'/> </hyperv> </features> <cpu mode='custom' match='exact'> <model fallback='allow'>SandyBridge</model> <topology sockets='1' cores='4' threads='1'/> </cpu> <clock offset='localtime'> <timer name='rtc' tickpolicy='catchup'/> <timer name='pit' tickpolicy='delay'/> <timer name='hpet' present='no'/> <timer name='hypervclock' present='yes'/> </clock> <on_poweroff>destroy</on_poweroff> <on_reboot>restart</on_reboot> <on_crash>restart</on_crash> <pm> <suspend-to-mem enabled='no'/> <suspend-to-disk enabled='no'/> </pm> <devices> <emulator>/usr/bin/kvm-spice</emulator> <disk type='file' device='disk'> <driver name='qemu' type='raw'/> <source file='/vm2/foo.img'/> <target dev='sda' bus='sata'/> <boot order='1'/> <address type='drive' controller='0' bus='0' target='0' unit='0'/> </disk> <disk type='block' device='cdrom'> <driver name='qemu' type='raw'/> <target dev='hdb' bus='ide'/> <readonly/> <boot order='2'/> <address type='drive' controller='0' bus='0' target='0' unit='1'/> </disk> <controller type='usb' index='0' model='ich9-ehci1'> <address type='pci' domain='0x0000' bus='0x00' slot='0x06' function='0x7'/> </controller> <controller type='usb' index='0' model='ich9-uhci1'> <master startport='0'/> <address type='pci' domain='0x0000' bus='0x00' slot='0x06' function='0x0' multifunction='on'/> </controller> <controller type='usb' index='0' model='ich9-uhci2'> <master startport='2'/> <address type='pci' domain='0x0000' bus='0x00' slot='0x06' function='0x1'/> </controller> <controller type='usb' index='0' model='ich9-uhci3'> <master startport='4'/> <address type='pci' domain='0x0000' bus='0x00' slot='0x06' function='0x2'/> </controller> <controller type='pci' index='0' model='pci-root'/> <controller type='ide' index='0'> <address type='pci' domain='0x0000' bus='0x00' slot='0x01' function='0x1'/> </controller> <controller type='virtio-serial' index='0'> <address type='pci' domain='0x0000' bus='0x00' slot='0x05' function='0x0'/> </controller> <controller type='sata' index='0'> <address type='pci' domain='0x0000' bus='0x00' slot='0x08' function='0x0'/> </controller> <interface type='direct'> <mac address='52:54:00:f0:47:f5'/> <source dev='p5p1' mode='bridge'/> <model type='e1000'/> <address type='pci' domain='0x0000' bus='0x00' slot='0x03' function='0x0'/> </interface> <serial type='pty'> <target port='0'/> </serial> <console type='pty'> <target type='serial' port='0'/> </console> <channel type='spicevmc'> <target type='virtio' name='com.redhat.spice.0'/> <address type='virtio-serial' controller='0' bus='0' port='1'/> </channel> <input type='tablet' bus='usb'/> <input type='mouse' bus='ps2'/> <input type='keyboard' bus='ps2'/> <graphics type='spice' autoport='yes'/> <video> <model type='cirrus' vram='16384' heads='1'/> <address type='pci' domain='0x0000' bus='0x00' slot='0x02' function='0x0'/> </video> <hostdev mode='subsystem' type='pci' managed='yes'> <source> <address domain='0x0000' bus='0x02' slot='0x00' function='0x0'/> </source> <address type='pci' domain='0x0000' bus='0x00' slot='0x04' function='0x0'/> </hostdev> <memballoon model='virtio'> <address type='pci' domain='0x0000' bus='0x00' slot='0x07' function='0x0'/> </memballoon> </devices> </domain> -- Alex Holst

2 3