[Bug 1869796] Re: vague error during upgrade

Wed Apr 1 08:24:03 UTC 2020

Turns out this is an ordering issue:

This makes it break:
$ deb-systemd-invoke restart 'libvirtd-ro.socket' 'libvirtd.service' 'libvirtd.socket' 'virtlockd.socket' 'virtlogd.socket'
Job failed. See "journalctl -xe" for details.

But this order would works:
$ deb-systemd-invoke restart 'libvirtd.service' 'libvirtd-ro.socket' 'libvirtd.socket' 'virtlockd.socket' 'virtlogd.socket

Ee didn't add/create this order - it is what dh_installsystemd made out of:
  dh_installsystemd -p libvirt-daemon-system --restart-after-upgrade libvirtd.service
due to:
root at f2:~# grep Also /lib/systemd/system/libvirtd.service
Also=virtlockd.socket
Also=virtlogd.socket
Also=libvirtd.socket
Also=libvirtd-ro.socket

That is just lexical order:
# printf "virtlockd.socket\nvirtlogd.socket\nlibvirtd.socket\nlibvirtd-ro.socket\nlibvirtd.service" | sort
libvirtd-ro.socket
libvirtd.service
libvirtd.socket
virtlockd.socket
virtlogd.socket

IMHO deb-systemd-invoke as generated by dh_installsystemd has a few options:
- should always start with the service it was called?
- should not pull in "ALSO" sockets (I guess it was intentional thou)
- dh-installsystemd could add an option to influence that as needed per-package.

Gladly for libvirt this is only a warning and not breaking any behavior
(as the later start recovers it). But I'll add a debhelper task for this
case.

Note: as I expected this issue affects Debians libvirt-daemon-system and
debhlper just as much.

** Changed in: libvirt (Ubuntu)
   Importance: Undecided => Low

** Also affects: debhelper (Ubuntu)
   Importance: Undecided
       Status: New

-- 
You received this bug notification because you are a member of Ubuntu
Foundations Bugs, which is subscribed to debhelper in Ubuntu.
https://bugs.launchpad.net/bugs/1869796

Title:
  vague error during upgrade

Status in debhelper package in Ubuntu:
  New
Status in libvirt package in Ubuntu:
  Confirmed

Bug description:
  Seen from today's apt upgrade on focal:

  Setting up libvirt-daemon-driver-qemu (6.0.0-0ubuntu6) ...
  Setting up libvirt-daemon (6.0.0-0ubuntu6) ...
  Setting up libvirt-daemon-driver-storage-rbd (6.0.0-0ubuntu6) ...
  Setting up libvirt-daemon-driver-storage-zfs (6.0.0-0ubuntu6) ...
  Setting up libvirt-daemon-system (6.0.0-0ubuntu6) ...
  Job failed. See "journalctl -xe" for details.
  virtlockd.service is a disabled or a static unit, not starting it.
  virtlogd.service is a disabled or a static unit, not starting it.
  Setting up libvirt-daemon dnsmasq configuration.

  Sadly 'journactl -xe' was useless. (It only showed a thousand unrelated lines.) A raw journalctl took forever to run long enough to let me see it generated two million lines of output, and started about two years ago, that I'm not keen on trying to run that through less or similar. Advice accepted.

  systemctl status looks good enough though:

  $ systemctl status 'libvirt*'
  ● libvirtd.socket - Libvirt local socket
       Loaded: loaded (/lib/systemd/system/libvirtd.socket; enabled; vendor preset: enabled)
       Active: active (running) since Mon 2020-03-30 20:44:55 UTC; 6min ago
     Triggers: ● libvirtd.service
       Listen: /run/libvirt/libvirt-sock (Stream)
        Tasks: 0 (limit: 18814)
       Memory: 0B
       CGroup: /system.slice/libvirtd.socket

  Mar 30 20:44:55 millbarge systemd[1]: Listening on Libvirt local
  socket.

  ● libvirtd.service - Virtualization daemon
       Loaded: loaded (/lib/systemd/system/libvirtd.service; enabled; vendor preset: enabled)
       Active: active (running) since Mon 2020-03-30 20:44:55 UTC; 6min ago
  TriggeredBy: ● libvirtd-admin.socket
               ● libvirtd.socket
               ● libvirtd-ro.socket
         Docs: man:libvirtd(8)
               https://libvirt.org
     Main PID: 3646435 (libvirtd)
        Tasks: 19 (limit: 32768)
       Memory: 26.9M
       CGroup: /system.slice/libvirtd.service
               ├─   3054 /usr/sbin/dnsmasq --conf-file=/var/lib/libvirt/dnsmasq/default.conf --leasefile-ro --dhcp-script=/usr/lib/libvirt/libvirt_leaseshelper
               ├─   3055 /usr/sbin/dnsmasq --conf-file=/var/lib/libvirt/dnsmasq/default.conf --leasefile-ro --dhcp-script=/usr/lib/libvirt/libvirt_leaseshelper
               └─3646435 /usr/sbin/libvirtd

  Mar 30 20:44:55 millbarge systemd[1]: Starting Virtualization daemon...
  Mar 30 20:44:55 millbarge systemd[1]: Started Virtualization daemon.
  Mar 30 20:44:55 millbarge dnsmasq[3054]: read /etc/hosts - 7 addresses
  Mar 30 20:44:55 millbarge dnsmasq[3054]: read /var/lib/libvirt/dnsmasq/default.addnhosts - 0 addresses
  Mar 30 20:44:55 millbarge dnsmasq-dhcp[3054]: read /var/lib/libvirt/dnsmasq/default.hostsfile

  ● libvirtd-admin.socket - Libvirt admin socket
       Loaded: loaded (/lib/systemd/system/libvirtd-admin.socket; enabled; vendor preset: enabled)
       Active: active (running) since Mon 2020-03-30 20:44:55 UTC; 6min ago
     Triggers: ● libvirtd.service
       Listen: /run/libvirt/libvirt-admin-sock (Stream)
        Tasks: 0 (limit: 18814)
       Memory: 0B
       CGroup: /system.slice/libvirtd-admin.socket

  Mar 30 20:44:55 millbarge systemd[1]: Listening on Libvirt admin
  socket.

  ● libvirt-guests.service - Suspend/Resume Running libvirt Guests
       Loaded: loaded (/lib/systemd/system/libvirt-guests.service; enabled; vendor preset: enabled)
       Active: active (exited) since Wed 2020-03-25 00:41:47 UTC; 5 days ago
         Docs: man:libvirtd(8)
               https://libvirt.org
     Main PID: 2808 (code=exited, status=0/SUCCESS)
        Tasks: 0 (limit: 18814)
       Memory: 0B
       CGroup: /system.slice/libvirt-guests.service

  Mar 25 00:41:47 millbarge systemd[1]: Starting Suspend/Resume Running libvirt Guests...
  Mar 25 00:41:47 millbarge systemd[1]: Finished Suspend/Resume Running libvirt Guests.

  ● libvirtd-ro.socket - Libvirt local read-only socket
       Loaded: loaded (/lib/systemd/system/libvirtd-ro.socket; enabled; vendor preset: enabled)
       Active: active (running) since Mon 2020-03-30 20:44:55 UTC; 6min ago
     Triggers: ● libvirtd.service
       Listen: /run/libvirt/libvirt-sock-ro (Stream)
        Tasks: 0 (limit: 18814)
       Memory: 0B
       CGroup: /system.slice/libvirtd-ro.socket

  Mar 30 20:44:55 millbarge systemd[1]: Listening on Libvirt local read-
  only socket.

  Thanks

  ProblemType: Bug
  DistroRelease: Ubuntu 20.04
  Package: libvirt-daemon 6.0.0-0ubuntu6
  ProcVersionSignature: Ubuntu 5.4.0-20.24-generic 5.4.27
  Uname: Linux 5.4.0-20-generic x86_64
  NonfreeKernelModules: zfs zunicode zavl icp zcommon znvpair
  ApportVersion: 2.20.11-0ubuntu21
  Architecture: amd64
  Date: Mon Mar 30 20:48:08 2020
  ProcEnviron:
   TERM=rxvt-unicode-256color
   PATH=(custom, no user)
   XDG_RUNTIME_DIR=<set>
   LANG=en_US.UTF-8
   SHELL=/bin/bash
  SourcePackage: libvirt
  UpgradeStatus: Upgraded to focal on 2020-01-24 (66 days ago)
  modified.conffile..etc.libvirt.nwfilter.allow-arp.xml: [inaccessible: [Errno 13] Permission denied: '/etc/libvirt/nwfilter/allow-arp.xml']
  modified.conffile..etc.libvirt.nwfilter.allow-dhcp-server.xml: [inaccessible: [Errno 13] Permission denied: '/etc/libvirt/nwfilter/allow-dhcp-server.xml']
  modified.conffile..etc.libvirt.nwfilter.allow-dhcp.xml: [inaccessible: [Errno 13] Permission denied: '/etc/libvirt/nwfilter/allow-dhcp.xml']
  modified.conffile..etc.libvirt.nwfilter.allow-incoming-ipv4.xml: [inaccessible: [Errno 13] Permission denied: '/etc/libvirt/nwfilter/allow-incoming-ipv4.xml']
  modified.conffile..etc.libvirt.nwfilter.allow-ipv4.xml: [inaccessible: [Errno 13] Permission denied: '/etc/libvirt/nwfilter/allow-ipv4.xml']
  modified.conffile..etc.libvirt.nwfilter.clean-traffic-gateway.xml: [inaccessible: [Errno 13] Permission denied: '/etc/libvirt/nwfilter/clean-traffic-gateway.xml']
  modified.conffile..etc.libvirt.nwfilter.clean-traffic.xml: [inaccessible: [Errno 13] Permission denied: '/etc/libvirt/nwfilter/clean-traffic.xml']
  modified.conffile..etc.libvirt.nwfilter.no-arp-ip-spoofing.xml: [inaccessible: [Errno 13] Permission denied: '/etc/libvirt/nwfilter/no-arp-ip-spoofing.xml']
  modified.conffile..etc.libvirt.nwfilter.no-arp-mac-spoofing.xml: [inaccessible: [Errno 13] Permission denied: '/etc/libvirt/nwfilter/no-arp-mac-spoofing.xml']
  modified.conffile..etc.libvirt.nwfilter.no-arp-spoofing.xml: [inaccessible: [Errno 13] Permission denied: '/etc/libvirt/nwfilter/no-arp-spoofing.xml']
  modified.conffile..etc.libvirt.nwfilter.no-ip-multicast.xml: [inaccessible: [Errno 13] Permission denied: '/etc/libvirt/nwfilter/no-ip-multicast.xml']
  modified.conffile..etc.libvirt.nwfilter.no-ip-spoofing.xml: [inaccessible: [Errno 13] Permission denied: '/etc/libvirt/nwfilter/no-ip-spoofing.xml']
  modified.conffile..etc.libvirt.nwfilter.no-mac-broadcast.xml: [inaccessible: [Errno 13] Permission denied: '/etc/libvirt/nwfilter/no-mac-broadcast.xml']
  modified.conffile..etc.libvirt.nwfilter.no-mac-spoofing.xml: [inaccessible: [Errno 13] Permission denied: '/etc/libvirt/nwfilter/no-mac-spoofing.xml']
  modified.conffile..etc.libvirt.nwfilter.no-other-l2-traffic.xml: [inaccessible: [Errno 13] Permission denied: '/etc/libvirt/nwfilter/no-other-l2-traffic.xml']
  modified.conffile..etc.libvirt.nwfilter.no-other-rarp-traffic.xml: [inaccessible: [Errno 13] Permission denied: '/etc/libvirt/nwfilter/no-other-rarp-traffic.xml']
  modified.conffile..etc.libvirt.nwfilter.qemu-announce-self-rarp.xml: [inaccessible: [Errno 13] Permission denied: '/etc/libvirt/nwfilter/qemu-announce-self-rarp.xml']
  modified.conffile..etc.libvirt.nwfilter.qemu-announce-self.xml: [inaccessible: [Errno 13] Permission denied: '/etc/libvirt/nwfilter/qemu-announce-self.xml']
  modified.conffile..etc.libvirt.qemu.conf: [inaccessible: [Errno 13] Permission denied: '/etc/libvirt/qemu.conf']
  modified.conffile..etc.libvirt.qemu.networks.default.xml: [inaccessible: [Errno 13] Permission denied: '/etc/libvirt/qemu/networks/default.xml']

To manage notifications about this bug go to:
https://bugs.launchpad.net/ubuntu/+source/debhelper/+bug/1869796/+subscriptions