FS#72425 - [systemd] 249.5-1 displays service warnings on boot

Attached to Project: Arch Linux
Opened by Rodrigo Contreras (rodancoci) - Friday, 15 October 2021, 05:10 GMT
Last edited by Christian Hesse (eworm) - Monday, 18 October 2021, 20:35 GMT
Task Type Bug Report
Category Packages: Core
Status Closed
Assigned To Christian Hesse (eworm)
Architecture All
Severity Medium
Priority Normal
Reported Version
Due in Version Undecided
Due Date Undecided
Percent Complete 100%
Votes 9
Private No

Details

After upgrading systemd and systemd-libs from 249.4-2 to 249.5-1 I was shown 5 warnings on boot:

[FAILED] Falied to start Entropy Daemon based on the HAVEGE algorithm.
[FAILED] Failed to start First Boot Wizard.
[FAILED] Failed to start Rebuild Hardware Database.
[FAILED] Failed to start Repartition Root Disk.
[FAILED] Failed to start Create System Users.


The relevant entries on journalctl entries seem to be:

Oct 15 01:24:50 ARCH-DT systemd[1]: haveged.service: Start request repeated too quickly.
Oct 15 01:24:50 ARCH-DT systemd[1]: haveged.service: Failed with result 'start-limit-hit'.
Oct 15 01:24:50 ARCH-DT systemd[1]: Failed to start Entropy Daemon based on the HAVEGE algorithm.
Oct 15 01:24:50 ARCH-DT systemd[1]: systemd-firstboot.service: Start request repeated too quickly.
Oct 15 01:24:50 ARCH-DT systemd[1]: systemd-firstboot.service: Failed with result 'start-limit-hit'.
Oct 15 01:24:50 ARCH-DT systemd[1]: Failed to start First Boot Wizard.
Oct 15 01:24:50 ARCH-DT systemd[1]: systemd-hwdb-update.service: Start request repeated too quickly.
Oct 15 01:24:50 ARCH-DT systemd[1]: systemd-hwdb-update.service: Failed with result 'start-limit-hit'.
Oct 15 01:24:50 ARCH-DT systemd[1]: Failed to start Rebuild Hardware Database.
Oct 15 01:24:50 ARCH-DT systemd[1]: systemd-repart.service: Start request repeated too quickly.
Oct 15 01:24:50 ARCH-DT systemd[1]: systemd-repart.service: Failed with result 'start-limit-hit'.
Oct 15 01:24:50 ARCH-DT systemd[1]: Failed to start Repartition Root Disk.
Oct 15 01:24:50 ARCH-DT systemd[1]: systemd-sysusers.service: Start request repeated too quickly.
Oct 15 01:24:50 ARCH-DT systemd[1]: systemd-sysusers.service: Failed with result 'start-limit-hit'.
Oct 15 01:24:50 ARCH-DT systemd[1]: Failed to start Create System Users.


Checking the status of each service shows similar outputs:

× haveged.service - Entropy Daemon based on the HAVEGE algorithm
Loaded: loaded (/usr/lib/systemd/system/haveged.service; enabled; vendor preset: disabled)
Active: failed (Result: start-limit-hit)
Condition: start condition failed at Fri 2021-10-15 01:24:49 -03; 42min ago
└─ ConditionKernelVersion=<5.6 was not met
Docs: man:haveged(8)
http://www.issihosts.com/haveged/

Oct 15 01:24:49 ARCH-DT systemd[1]: Condition check resulted in Entropy Daemon based on the HAVEGE algorithm being skipped.
Oct 15 01:24:49 ARCH-DT systemd[1]: Condition check resulted in Entropy Daemon based on the HAVEGE algorithm being skipped.
Oct 15 01:24:49 ARCH-DT systemd[1]: Condition check resulted in Entropy Daemon based on the HAVEGE algorithm being skipped.
Oct 15 01:24:49 ARCH-DT systemd[1]: Condition check resulted in Entropy Daemon based on the HAVEGE algorithm being skipped.
Oct 15 01:24:50 ARCH-DT systemd[1]: haveged.service: Start request repeated too quickly.
Oct 15 01:24:50 ARCH-DT systemd[1]: haveged.service: Failed with result 'start-limit-hit'.
Oct 15 01:24:50 ARCH-DT systemd[1]: Failed to start Entropy Daemon based on the HAVEGE algorithm.


× systemd-firstboot.service - First Boot Wizard
Loaded: loaded (/usr/lib/systemd/system/systemd-firstboot.service; static)
Active: failed (Result: start-limit-hit)
Condition: start condition failed at Fri 2021-10-15 01:24:49 -03; 12min ago
└─ ConditionFirstBoot=yes was not met
Docs: man:systemd-firstboot(1)

Oct 15 01:24:49 ARCH-DT systemd[1]: Condition check resulted in First Boot Wizard being skipped.
Oct 15 01:24:49 ARCH-DT systemd[1]: Condition check resulted in First Boot Wizard being skipped.
Oct 15 01:24:49 ARCH-DT systemd[1]: Condition check resulted in First Boot Wizard being skipped.
Oct 15 01:24:49 ARCH-DT systemd[1]: Condition check resulted in First Boot Wizard being skipped.
Oct 15 01:24:50 ARCH-DT systemd[1]: systemd-firstboot.service: Start request repeated too quickly.
Oct 15 01:24:50 ARCH-DT systemd[1]: systemd-firstboot.service: Failed with result 'start-limit-hit'.
Oct 15 01:24:50 ARCH-DT systemd[1]: Failed to start First Boot Wizard.


× systemd-hwdb-update.service - Rebuild Hardware Database
Loaded: loaded (/usr/lib/systemd/system/systemd-hwdb-update.service; static)
Active: failed (Result: start-limit-hit)
Condition: start condition failed at Fri 2021-10-15 01:24:49 -03; 12min ago
├─ ConditionNeedsUpdate=/etc was not met
├─ ConditionPathExists=|!/usr/lib/udev/hwdb.bin was not met
├─ ConditionPathExists=|/etc/udev/hwdb.bin was not met
└─ ConditionDirectoryNotEmpty=|/etc/udev/hwdb.d was not met
Docs: man:hwdb(7)
man:systemd-hwdb(8)

Oct 15 01:24:49 ARCH-DT systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
Oct 15 01:24:49 ARCH-DT systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
Oct 15 01:24:49 ARCH-DT systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
Oct 15 01:24:49 ARCH-DT systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped.
Oct 15 01:24:50 ARCH-DT systemd[1]: systemd-hwdb-update.service: Start request repeated too quickly.
Oct 15 01:24:50 ARCH-DT systemd[1]: systemd-hwdb-update.service: Failed with result 'start-limit-hit'.
Oct 15 01:24:50 ARCH-DT systemd[1]: Failed to start Rebuild Hardware Database.


× systemd-repart.service - Repartition Root Disk
Loaded: loaded (/usr/lib/systemd/system/systemd-repart.service; static)
Active: failed (Result: start-limit-hit)
Condition: start condition failed at Fri 2021-10-15 01:24:49 -03; 12min ago
├─ ConditionDirectoryNotEmpty=|/usr/lib/repart.d was not met
├─ ConditionDirectoryNotEmpty=|/usr/local/lib/repart.d was not met
├─ ConditionDirectoryNotEmpty=|/etc/repart.d was not met
├─ ConditionDirectoryNotEmpty=|/run/repart.d was not met
├─ ConditionDirectoryNotEmpty=|/sysroot/usr/lib/repart.d was not met
├─ ConditionDirectoryNotEmpty=|/sysroot/usr/local/lib/repart.d was not met
├─ ConditionDirectoryNotEmpty=|/sysroot/etc/repart.d was not met
├─ ConditionDirectoryNotEmpty=|/sysusr/usr/lib/repart.d was not met
└─ ConditionDirectoryNotEmpty=|/sysusr/usr/local/lib/repart.d was not met
Docs: man:systemd-repart.service(8)

Oct 15 01:24:49 ARCH-DT systemd[1]: Condition check resulted in Repartition Root Disk being skipped.
Oct 15 01:24:49 ARCH-DT systemd[1]: Condition check resulted in Repartition Root Disk being skipped.
Oct 15 01:24:49 ARCH-DT systemd[1]: Condition check resulted in Repartition Root Disk being skipped.
Oct 15 01:24:49 ARCH-DT systemd[1]: Condition check resulted in Repartition Root Disk being skipped.
Oct 15 01:24:50 ARCH-DT systemd[1]: systemd-repart.service: Start request repeated too quickly.
Oct 15 01:24:50 ARCH-DT systemd[1]: systemd-repart.service: Failed with result 'start-limit-hit'.
Oct 15 01:24:50 ARCH-DT systemd[1]: Failed to start Repartition Root Disk.


× systemd-sysusers.service - Create System Users
Loaded: loaded (/usr/lib/systemd/system/systemd-sysusers.service; static)
Active: failed (Result: start-limit-hit)
Condition: start condition failed at Fri 2021-10-15 01:24:49 -03; 13min ago
└─ ConditionNeedsUpdate=/etc was not met
Docs: man:sysusers.d(5)
man:systemd-sysusers.service(8)

Oct 15 01:24:49 ARCH-DT systemd[1]: Condition check resulted in Create System Users being skipped.
Oct 15 01:24:49 ARCH-DT systemd[1]: Condition check resulted in Create System Users being skipped.
Oct 15 01:24:49 ARCH-DT systemd[1]: Condition check resulted in Create System Users being skipped.
Oct 15 01:24:49 ARCH-DT systemd[1]: Condition check resulted in Create System Users being skipped.
Oct 15 01:24:50 ARCH-DT systemd[1]: systemd-sysusers.service: Start request repeated too quickly.
Oct 15 01:24:50 ARCH-DT systemd[1]: systemd-sysusers.service: Failed with result 'start-limit-hit'.
Oct 15 01:24:50 ARCH-DT systemd[1]: Failed to start Create System Users.
This task depends upon

Closed by  Christian Hesse (eworm)
Monday, 18 October 2021, 20:35 GMT
Reason for closing:  Fixed
Additional comments about closing:  systemd 249.5-2
Comment by Joplin Huge (Archislove) - Saturday, 16 October 2021, 16:39 GMT
I have the same problem but a little different: I do not have haveged.service, systemd-binfmt fails, systemd-sysusers doesn't fail, the rest of them look basically the same.

Also I noticed the I have the five warnings repeated many times.
Comment by loqs (loqs) - Saturday, 16 October 2021, 21:31 GMT
Is there an upstream bug report? Have you tried reverting [1]?
Edit:
Upstream issue [2].

[1] https://github.com/systemd/systemd-stable/commit/ed8fbbf1745c6a2dc0b8cd560ac8a3353f72e979
[2] https://github.com/systemd/systemd/issues/21025
Comment by Rodrigo Contreras (rodancoci) - Sunday, 17 October 2021, 21:29 GMT
I had not seen that issue at the time of reporting the bug. It seems the issue is with version 249.5 of systemd; I downgraded systemd, systemd-libs, systemd-sysvcompat and lib32-systemd to version 249.4-2 and that got rid of the issue.
Comment by loqs (loqs) - Sunday, 17 October 2021, 22:06 GMT
[1] systemd-249.5-1.1.tar contains the systemd 249.5 packages with ed8fbbf1745c6a2dc0b8cd560ac8a3353f72e979 reverted, pkgrel is incremented to 1.1.
If you install those packages is the issue still present?

[1] https://drive.google.com/file/d/1Tgjj2rysQefsbE80cjD4Sqj6QkrS3q_o/view?usp=sharing
Comment by Rodrigo Contreras (rodancoci) - Monday, 18 October 2021, 02:52 GMT
Installing the packages in the supplied archive resulted in no errors being shown on boot. Aditionally, systemd-sysusers runs successfully. It definitely seems that commit ed8fbbf1745c6a2dc0b8cd560ac8a3353f72e979 is the culprit.
Comment by Joplin Huge (Archislove) - Monday, 18 October 2021, 10:12 GMT
I can confirm that installing the four packages in the tar provided results in the warnings not being showed and systemd-binfmt, systemd-firstboot, systemd-hwdb-update, systemd-repart being dead(inactive) with "start condition failed", they're not shown anymore using systemctl.

Loading...