bug#64073: libvirtd requires restart to function

bug#64073: libvirtd requires restart to function

[Christian Miller via Bug reports for GNU Guix]

[-- Attachment #1: Type: text/plain, Size: 333 bytes --]

Currently libvirtd does not work without running "sudo herd restart libvirtd". Upon starting virt-manager I can't connect to the socket but if I restart libvirtd it works fine.

I tested the 1.4.0 image in a VM and there is no issue. After that I did a guix pull and reconfigured the system and I have the same issue again in the VM.

[-- Attachment #2: Type: text/html, Size: 618 bytes --]

bug#64073: libvirtd requires restart to function

[Ludovic Courtès]

Hi,

Christian Miller <miller.christian@proton.me> skribis:

> Currently libvirtd does not work without running "sudo herd restart
> libvirtd". Upon starting virt-manager I can't connect to the socket
> but if I restart libvirtd it works fine.
>
> I tested the 1.4.0 image in a VM and there is no issue. After that I
> did a guix pull and reconfigured the system and I have the same issue
> again in the VM.

Are there any hints in /var/log/messages as to what goes wrong with
libvirtd when it is first started?

I suspect it’s getting started “too early”.

Thanks,
Ludo’.

bug#64073: libvirtd requires restart to function

[Christian Miller via Bug reports for GNU Guix]

[-- Attachment #1: Type: text/plain, Size: 1308 bytes --]

Hi,

there is not an error message upon starting it but by stopping it:
Jun 14 23:52:18 localhost shepherd[1]: Stopping service libvirtd... 
Jun 14 23:52:18 localhost libvirtd: 157: error : virGDBusGetSystemBus:99 : internal error: Unable to get system bus connection: Could not connect: No such file or directory 

I have added the command output of "grep -i libvirt /var/log/messages" as attachment.

NOTE: That permission denied error on the ISO is happening since I copied /etc/libvirt from my old system.  I have 10+ VMs and thought this works as well as of re importing every vm.

PS:
I wanted to create a VM to not taint logs but since I upgraded the VM I just have a black screen.  It was a Guix VM installed through the installer with GNOME.  I ran guix pull and added spice, libvirt and virtlog to the config.  After guix system reconfigure config.scm I know have a black screen upon logging in to the system.  This was only after reconfiguring the system.  Before it worked normally.  I used the ISO that reports a permission denied error in the logs I attached.  GDM works fine but after logging it is just a black screen.  Also my mouse cursor changes from a arrow pointer to the default cross cursor from x11.  I guess something is broken on latest?

Best Regards
Christian Miller

[-- Attachment #2: log.txt --]
[-- Type: text/plain, Size: 9773 bytes --]

Jun 12 16:20:35 localhost vmunix: [    1.669057] shepherd[1]: Starting service libvirtd...
Jun 12 16:20:35 localhost vmunix: [    1.690558] shepherd[1]: Service libvirtd started.
Jun 12 16:20:35 localhost vmunix: [    1.696205] shepherd[1]: Service libvirtd running with value 155.
Jun 12 16:20:35 localhost vmunix: [    1.696425] shepherd[1]: Service libvirtd has been started.
Jun 13 11:38:06 localhost vmunix: [    1.687605] shepherd[1]: Starting service libvirtd...
Jun 13 11:38:06 localhost vmunix: [    1.708965] shepherd[1]: Service libvirtd started.
Jun 13 11:38:06 localhost vmunix: [    1.714310] shepherd[1]: Service libvirtd running with value 155.
Jun 13 11:38:06 localhost vmunix: [    1.714578] shepherd[1]: Service libvirtd has been started.
Jun 13 17:42:57 localhost shepherd[1]: Stopping service libvirtd... 
Jun 13 17:42:57 localhost libvirtd: 155: error : virGDBusGetSystemBus:99 : internal error: Unable to get system bus connection: Could not connect: No such file or directory 
Jun 13 17:42:57 localhost shepherd[1]: Service libvirtd stopped. 
Jun 13 17:42:57 localhost shepherd[1]: Service libvirtd is now stopped. 
Jun 13 17:42:57 localhost shepherd[1]: Starting service libvirtd... 
Jun 13 17:42:57 localhost shepherd[1]: Service libvirtd has been started. 
Jun 13 17:42:57 localhost shepherd[1]: Service libvirtd started. 
Jun 13 17:42:57 localhost shepherd[1]: Service libvirtd running with value 9832. 
Jun 13 17:42:57 localhost dnsmasq[361]: read /var/lib/libvirt/dnsmasq/network.addnhosts - 0 names
Jun 13 17:42:57 localhost dnsmasq-dhcp[361]: read /var/lib/libvirt/dnsmasq/network.hostsfile
Jun 14 05:10:37 localhost shepherd[1]: Respawning libvirtd. 
Jun 14 05:10:37 localhost shepherd[1]: Starting service libvirtd... 
Jun 14 05:10:37 localhost shepherd[1]: Service libvirtd started. 
Jun 14 05:10:37 localhost shepherd[1]: Service libvirtd running with value 27278. 
Jun 14 05:10:37 localhost shepherd[1]: Service libvirtd has been started. 
Jun 14 16:00:12 localhost vmunix: [    1.662704] shepherd[1]: Starting service libvirtd...
Jun 14 16:00:12 localhost vmunix: [    1.684551] shepherd[1]: Service libvirtd started.
Jun 14 16:00:12 localhost vmunix: [    1.689707] shepherd[1]: Service libvirtd running with value 159.
Jun 14 16:00:12 localhost vmunix: [    1.689941] shepherd[1]: Service libvirtd has been started.
Jun 14 22:01:48 localhost vmunix: [    1.672804] shepherd[1]: Starting service libvirtd...
Jun 14 22:01:48 localhost vmunix: [    1.694130] shepherd[1]: Service libvirtd started.
Jun 14 22:01:48 localhost vmunix: [    1.699300] shepherd[1]: Service libvirtd running with value 158.
Jun 14 22:01:48 localhost vmunix: [    1.699497] shepherd[1]: Service libvirtd has been started.
Jun 14 22:31:32 localhost vmunix: [    1.748114] shepherd[1]: Starting service libvirtd...
Jun 14 22:31:32 localhost vmunix: [    1.769690] shepherd[1]: Service libvirtd started.
Jun 14 22:31:32 localhost vmunix: [    1.774882] shepherd[1]: Service libvirtd running with value 157.
Jun 14 22:31:32 localhost vmunix: [    1.775099] shepherd[1]: Service libvirtd has been started.
Jun 14 23:52:18 localhost shepherd[1]: Stopping service libvirtd... 
Jun 14 23:52:18 localhost libvirtd: 157: error : virGDBusGetSystemBus:99 : internal error: Unable to get system bus connection: Could not connect: No such file or directory 
Jun 14 23:52:18 localhost shepherd[1]: Service libvirtd stopped. 
Jun 14 23:52:18 localhost shepherd[1]: Service libvirtd is now stopped. 
Jun 14 23:52:18 localhost shepherd[1]: Starting service libvirtd... 
Jun 14 23:52:18 localhost shepherd[1]: Service libvirtd has been started. 
Jun 14 23:52:18 localhost shepherd[1]: Service libvirtd started. 
Jun 14 23:52:18 localhost shepherd[1]: Service libvirtd running with value 6177. 
Jun 14 23:52:18 localhost dnsmasq[362]: read /var/lib/libvirt/dnsmasq/network.addnhosts - 0 names
Jun 14 23:52:18 localhost dnsmasq-dhcp[362]: read /var/lib/libvirt/dnsmasq/network.hostsfile
Jun 15 04:39:36 localhost shepherd[1]: Respawning libvirtd. 
Jun 15 04:39:36 localhost shepherd[1]: Starting service libvirtd... 
Jun 15 04:39:36 localhost shepherd[1]: Service libvirtd started. 
Jun 15 04:39:36 localhost shepherd[1]: Service libvirtd running with value 14395. 
Jun 15 04:39:36 localhost shepherd[1]: Service libvirtd has been started. 
Jun 15 16:02:13 localhost vmunix: [    1.682893] shepherd[1]: Starting service libvirtd...
Jun 15 16:02:13 localhost vmunix: [    1.704532] shepherd[1]: Service libvirtd started.
Jun 15 16:02:13 localhost vmunix: [    1.709753] shepherd[1]: Service libvirtd running with value 157.
Jun 15 16:02:13 localhost vmunix: [    1.710035] shepherd[1]: Service libvirtd has been started.
Jun 16 15:28:56 localhost vmunix: [    1.687354] shepherd[1]: Starting service libvirtd...
Jun 16 15:28:56 localhost vmunix: [    1.709063] shepherd[1]: Service libvirtd started.
Jun 16 15:28:56 localhost vmunix: [    1.714333] shepherd[1]: Service libvirtd running with value 158.
Jun 16 15:28:56 localhost vmunix: [    1.714565] shepherd[1]: Service libvirtd has been started.
Jun 16 20:20:21 localhost libvirtd: 168: error : virGDBusGetSystemBus:99 : internal error: Unable to get system bus connection: Could not connect: No such file or directory 
Jun 16 20:20:21 localhost libvirtd: 158: error : virNetSocketReadWire:1791 : End of file while reading data: Input/output error 
Jun 16 20:22:51 localhost shepherd[1]: Stopping service libvirtd... 
Jun 16 20:22:51 localhost libvirtd: 158: error : virGDBusGetSystemBus:99 : internal error: Unable to get system bus connection: Could not connect: No such file or directory 
Jun 16 20:22:51 localhost shepherd[1]: Service libvirtd stopped. 
Jun 16 20:22:51 localhost shepherd[1]: Service libvirtd is now stopped. 
Jun 16 20:22:51 localhost shepherd[1]: Starting service libvirtd... 
Jun 16 20:22:51 localhost shepherd[1]: Service libvirtd has been started. 
Jun 16 20:22:51 localhost shepherd[1]: Service libvirtd started. 
Jun 16 20:22:51 localhost shepherd[1]: Service libvirtd running with value 6718. 
Jun 16 20:22:51 localhost dnsmasq[361]: read /var/lib/libvirt/dnsmasq/network.addnhosts - 0 names
Jun 16 20:22:51 localhost dnsmasq-dhcp[361]: read /var/lib/libvirt/dnsmasq/network.hostsfile
Jun 16 20:28:38 localhost libvirtd: 7721: info : libvirt version: 8.6.0 
Jun 16 20:28:38 localhost libvirtd: 7721: info : hostname: gnu 
Jun 16 20:28:38 localhost libvirtd: 7721: error : qemuMonitorIORead:456 : Unable to read from monitor: Connection reset by peer 
Jun 16 20:28:38 localhost libvirtd: 7721: error : qemuProcessReportLogError:2026 : internal error: qemu unexpectedly closed the monitor: 2023-06-16T18:28:38.579248Z qemu-system-x86_64: -blockdev {"driver":"file","filename":"/home/cm/Downloads/7hcg4b2sh2yq0mzzinps4x5wm5x17bl3-image.iso","node-name":"libvirt-1-storage","auto-read-only":true,"discard":"unmap"}: Could not open '/home/cm/Downloads/7hcg4b2sh2yq0mzzinps4x5wm5x17bl3-image.iso': Permission denied 
Jun 16 20:28:38 localhost libvirtd: 6723: error : qemuProcessReportLogError:2026 : internal error: process exited while connecting to monitor: 2023-06-16T18:28:38.579248Z qemu-system-x86_64: -blockdev {"driver":"file","filename":"/home/cm/Downloads/7hcg4b2sh2yq0mzzinps4x5wm5x17bl3-image.iso","node-name":"libvirt-1-storage","auto-read-only":true,"discard":"unmap"}: Could not open '/home/cm/Downloads/7hcg4b2sh2yq0mzzinps4x5wm5x17bl3-image.iso': Permission denied 
Jun 16 20:29:56 localhost libvirtd: 6722: warning : virStorageBackendVolOpen:1619 : ignoring FIFO '/tmp/polybar_mqueue.718' 
Jun 16 20:48:58 localhost libvirtd: 6723: warning : virStorageBackendVolOpen:1619 : ignoring FIFO '/tmp/polybar_mqueue.718' 
Jun 16 22:19:17 localhost shepherd[1]: Stopping service libvirtd... 
Jun 16 22:19:17 localhost shepherd[1]: Service libvirtd stopped. 
Jun 16 22:19:17 localhost shepherd[1]: Service libvirtd is now stopped. 
Jun 16 22:19:17 localhost shepherd[1]: Starting service libvirtd... 
Jun 16 22:19:17 localhost shepherd[1]: Service libvirtd has been started. 
Jun 16 22:19:17 localhost shepherd[1]: Service libvirtd started. 
Jun 16 22:19:17 localhost shepherd[1]: Service libvirtd running with value 15328. 
Jun 16 22:19:17 localhost dnsmasq[361]: read /var/lib/libvirt/dnsmasq/network.addnhosts - 0 names
Jun 16 22:19:17 localhost dnsmasq-dhcp[361]: read /var/lib/libvirt/dnsmasq/network.hostsfile
Jun 16 22:19:17 localhost libvirtd: 15340: info : libvirt version: 8.6.0 
Jun 16 22:19:17 localhost libvirtd: 15340: info : hostname: gnu 
Jun 16 22:19:17 localhost libvirtd: 15340: warning : virStorageBackendVolOpen:1619 : ignoring FIFO '/tmp/polybar_mqueue.718' 
Jun 16 22:19:17 localhost libvirtd: 15340: warning : virStorageBackendVolOpen:1619 : ignoring FIFO '/tmp/polybar_mqueue.14245' 
Jun 16 22:19:21 localhost libvirtd: 15332: warning : virStorageBackendVolOpen:1619 : ignoring FIFO '/tmp/polybar_mqueue.718' 
Jun 16 22:19:21 localhost libvirtd: 15332: warning : virStorageBackendVolOpen:1619 : ignoring FIFO '/tmp/polybar_mqueue.14245' 
Jun 16 22:23:14 localhost shepherd[1]: Respawning libvirtd. 
Jun 16 22:23:14 localhost shepherd[1]: Starting service libvirtd... 
Jun 16 22:23:14 localhost shepherd[1]: Service libvirtd started. 
Jun 16 22:23:14 localhost shepherd[1]: Service libvirtd running with value 15956. 
Jun 16 22:23:14 localhost shepherd[1]: Service libvirtd has been started. 
Jun 16 22:23:46 localhost vmunix: [    1.711901] shepherd[1]: Starting service libvirtd...
Jun 16 22:23:46 localhost vmunix: [    1.732802] shepherd[1]: Service libvirtd started.
Jun 16 22:23:46 localhost vmunix: [    1.738023] shepherd[1]: Service libvirtd running with value 158.
Jun 16 22:23:46 localhost vmunix: [    1.738233] shepherd[1]: Service libvirtd has been started.

bug#64073: libvirtd requires restart to function

[Josselin Poiret via Bug reports for GNU Guix]

[-- Attachment #1: Type: text/plain, Size: 634 bytes --]

Hi Christian,

Christian Miller via Bug reports for GNU Guix <bug-guix@gnu.org> writes:

> Hi,
>
> there is not an error message upon starting it but by stopping it:
> Jun 14 23:52:18 localhost shepherd[1]: Stopping service libvirtd... 
> Jun 14 23:52:18 localhost libvirtd: 157: error : virGDBusGetSystemBus:99 : internal error: Unable to get system bus connection: Could not connect: No such file or directory 

Seems that dbus is needed for libvirt, but it is not listed as a
requirement for the shepherd service.  I don't use libvirt, but would
you be able to test the following patch?

Best,
-- 
Josselin Poiret

[-- Attachment #2: signature.asc --]
[-- Type: application/pgp-signature, Size: 682 bytes --]

bug#64073: [PATCH] services: libvirt: Add requirement on dbus.

[Josselin Poiret via Bug reports for GNU Guix]

From: Josselin Poiret <dev@jpoiret.xyz>

* gnu/services/virtualization.scm (libvirt-shepherd-service): Add requirement
on dbus.
---
 gnu/services/virtualization.scm | 1 +
 1 file changed, 1 insertion(+)

diff --git a/gnu/services/virtualization.scm b/gnu/services/virtualization.scm
index 880557915c..506f5a7ab6 100644
--- a/gnu/services/virtualization.scm
+++ b/gnu/services/virtualization.scm
@@ -478,6 +478,7 @@ (define (libvirt-shepherd-service config)
     (list (shepherd-service
            (documentation "Run the libvirt daemon.")
            (provision '(libvirtd))
+           (requirement '(dbus-system))
            (start #~(make-forkexec-constructor
                      (list (string-append #$libvirt "/sbin/libvirtd")
                            "-f" #$config-file
-- 
2.40.1

bug#64073: libvirtd requires restart to function

[Christian Miller via Bug reports for GNU Guix]

Hello,

I tested your patch and it works.  With the patch virt-manager does instantly connect without any manual interference.

Since this is the first time I did this, just to be sure I did everything correctly, I list how I tested it.

I cloned the git repository
I created a file called libvirtd.patch with your contents
git apply libvirtd.patch
guix shell -D guix --pure
./bootstrap
./configure --localstatedir=/var
make
make check (had 2 errors, authenticate and something else)
after that I executed "sudo -E ./pre-inst-env guix system reconfigure ~/guix/system.cm"
I rebooted the system and launched virt-manager which directly connected.

Best Regards,
Christian Miller

bug#64073: libvirtd requires restart to function

[Josselin Poiret via Bug reports for GNU Guix]

[-- Attachment #1: Type: text/plain, Size: 1254 bytes --]

Hi Christian,

Christian Miller via Bug reports for GNU Guix <bug-guix@gnu.org> writes:

> I tested your patch and it works.  With the patch virt-manager does instantly connect without any manual interference.
>
> Since this is the first time I did this, just to be sure I did everything correctly, I list how I tested it.
>
> I cloned the git repository
> I created a file called libvirtd.patch with your contents
> git apply libvirtd.patch
> guix shell -D guix --pure
> ./bootstrap
> ./configure --localstatedir=/var
> make
> make check (had 2 errors, authenticate and something else)
> after that I executed "sudo -E ./pre-inst-env guix system reconfigure ~/guix/system.cm"
> I rebooted the system and launched virt-manager which directly connected.

Great!  Thanks for going the extra mile testing this, I didn't know how
familiar you were with the Guix development process.  We could also
advertise the simpler `guix time-machine` with the branches from [1],
although processing the new patch is not instantaneous (and I don't
think bug reports are scanned for patches).

In any case, I'll try to push it ASAP with some other changes!

[1] https://git.guix-patches.cbaines.net/guix-patches

Best,
-- 
Josselin Poiret

[-- Attachment #2: signature.asc --]
[-- Type: application/pgp-signature, Size: 682 bytes --]

bug#64073: libvirtd requires restart to function

[Josselin Poiret via Bug reports for GNU Guix]

[-- Attachment #1: Type: text/plain, Size: 563 bytes --]

Hi Christian,

Josselin Poiret <dev@jpoiret.xyz> writes:

> Great!  Thanks for going the extra mile testing this, I didn't know how
> familiar you were with the Guix development process.  We could also
> advertise the simpler `guix time-machine` with the branches from [1],
> although processing the new patch is not instantaneous (and I don't
> think bug reports are scanned for patches).
>
> In any case, I'll try to push it ASAP with some other changes!

Pushed as 612399df3edcbe4d1b1da784bd23440398d27454, closing.

Best,
-- 
Josselin Poiret

[-- Attachment #2: signature.asc --]
[-- Type: application/pgp-signature, Size: 682 bytes --]