Bug 2065322

Summary: systemd services fail to start
Product: Red Hat Enterprise Linux 8 Reporter: GV <rhel>
Component: systemdAssignee: David Tardon <dtardon>
Status: CLOSED ERRATA QA Contact: Frantisek Sumsal <fsumsal>
Severity: high Docs Contact:
Priority: high    
Version: 8.5CC: benny_bao, dtardon, frank.ramsay, jamacku, jbilling, kazen, lombcy, mateusz.kusiak, msekleta, rmetrich, sgardner, smahanga, soutteri, systemd-maint-list, yijun_shen
Target Milestone: rcKeywords: Bugfix, Triaged, ZStream
Target Release: 8.7   
Hardware: x86_64   
OS: Linux   
Whiteboard:
Fixed In Version: systemd-239-60.el8 Doc Type: If docs needed, set a value
Doc Text:
Story Points: ---
Clone Of:
: 2086553 (view as bug list) Environment:
Last Closed: 2022-11-08 10:49:54 UTC Type: Bug
Regression: --- Mount Type: ---
Documentation: --- CRM:
Verified Versions: Category: ---
oVirt Team: --- RHEL 7.3 requirements from Atomic Host:
Cloudforms Team: --- Target Upstream Version:
Embargoed:
Bug Depends On:    
Bug Blocks: 1798275, 2016147, 2072062, 1846002, 1965064, 2086553    
Deadline: 2022-08-23   
Attachments:
Description Flags
Very blurry image none

Description GV 2022-03-17 16:46:37 UTC
Description of problem:
After systemd was updated to version systemd-239-51.el8_5.5.x86_64, at every reboot the following services fail to start:

# grep -E "loadmodules|sys-fs-fuse-connections|systemd-sysusers|systemd-hwdb-update" /var/log/messages
Mar 17 07:55:12 xxx systemd[1]: loadmodules.service: Start request repeated too quickly.
Mar 17 07:55:12 xxx systemd[1]: loadmodules.service: Failed with result 'start-limit-hit'.
Mar 17 07:55:12 xxx systemd[1]: sys-fs-fuse-connections.mount: Start request repeated too quickly.
Mar 17 07:55:12 xxx systemd[1]: sys-fs-fuse-connections.mount: Failed with result 'start-limit-hit'.
Mar 17 07:55:12 xxx systemd[1]: systemd-sysusers.service: Start request repeated too quickly.
Mar 17 07:55:12 xxx systemd[1]: systemd-sysusers.service: Failed with result 'start-limit-hit'.
Mar 17 07:55:12 xxx systemd[1]: systemd-hwdb-update.service: Start request repeated too quickly.

Everything else work. Reverting to systemd-239-51.el8_5.3.x86_64 and all the above services start just fine.

Version-Release number of selected component (if applicable):
systemd-239-51.el8_5.5.x86_64

Comment 1 David Tardon 2022-03-17 18:17:40 UTC
Please attach the logs for these services. E.g.:

# journalctl -b -u loadmodules.service

Comment 2 GV 2022-03-17 19:57:42 UTC
# systemctl reset-failed loadmodules
# systemctl status loadmodules
● loadmodules.service - Load legacy module configuration
   Loaded: loaded (/usr/lib/systemd/system/loadmodules.service; enabled; vendor preset: enabled)
   Active: inactive (dead)
Condition: start condition failed at Thu 2022-03-17 21:29:44 EET; 14min ago
           ├─ ConditionPathExists=|/etc/rc.modules was not met
           └─ ConditionDirectoryNotEmpty=|/etc/sysconfig/modules was not met
# systemctl reset-failed sys-fs-fuse-connections.mount
# systemctl status sys-fs-fuse-connections.mount
● sys-fs-fuse-connections.mount - FUSE Control File System
   Loaded: loaded (/usr/lib/systemd/system/sys-fs-fuse-connections.mount; static; vendor preset: disabled)
   Active: inactive (dead)
Condition: start condition failed at Thu 2022-03-17 21:29:44 EET; 14min ago
           └─ ConditionPathExists=/sys/fs/fuse/connections was not met
    Where: /sys/fs/fuse/connections
     What: fusectl
     Docs: https://www.kernel.org/doc/Documentation/filesystems/fuse.txt
           https://www.freedesktop.org/wiki/Software/systemd/APIFileSystems
# systemctl reset-failed systemd-sysusers.service
# systemctl status systemd-sysusers.service
● systemd-sysusers.service - Create System Users
   Loaded: loaded (/usr/lib/systemd/system/systemd-sysusers.service; static; vendor preset: disabled)
   Active: inactive (dead)
Condition: start condition failed at Thu 2022-03-17 21:29:44 EET; 14min ago
           └─ ConditionNeedsUpdate=/etc was not met
     Docs: man:sysusers.d(5)
           man:systemd-sysusers.service(8)
# systemctl reset-failed systemd-hwdb-update.service
# systemctl status systemd-hwdb-update.service
● systemd-hwdb-update.service - Rebuild Hardware Database
   Loaded: loaded (/usr/lib/systemd/system/systemd-hwdb-update.service; static; vendor preset: disabled)
   Active: inactive (dead)
Condition: start condition failed at Thu 2022-03-17 21:29:44 EET; 14min ago
           ├─ ConditionNeedsUpdate=/etc was not met
           └─ ConditionDirectoryNotEmpty=|/etc/udev/hwdb.d was not met
     Docs: man:hwdb(7)
           man:systemd-hwdb(8)

.... reboot ....

#journalctl -b -u loadmodules
-- Logs begin at Thu 2022-03-17 21:45:33 EET, end at Thu 2022-03-17 21:48:11 EET. --
-- No entries --
# systemctl status loadmodules
● loadmodules.service - Load legacy module configuration
   Loaded: loaded (/usr/lib/systemd/system/loadmodules.service; enabled; vendor preset: enabled)
   Active: failed (Result: start-limit-hit)
Condition: start condition failed at Thu 2022-03-17 21:45:41 EET; 2min 30s ago
           ├─ ConditionPathExists=|/etc/rc.modules was not met
           └─ ConditionDirectoryNotEmpty=|/etc/sysconfig/modules was not met
#journalctl -b -u sys-fs-fuse-connections.mount
-- Logs begin at Thu 2022-03-17 21:45:33 EET, end at Thu 2022-03-17 21:48:11 EET. --
-- No entries --
# systemctl status sys-fs-fuse-connections.mount
● sys-fs-fuse-connections.mount - FUSE Control File System
   Loaded: loaded (/usr/lib/systemd/system/sys-fs-fuse-connections.mount; static; vendor preset: disabled)
   Active: failed (Result: start-limit-hit)
Condition: start condition failed at Thu 2022-03-17 21:45:41 EET; 2min 30s ago
           └─ ConditionPathExists=/sys/fs/fuse/connections was not met
    Where: /sys/fs/fuse/connections
     What: fusectl
     Docs: https://www.kernel.org/doc/Documentation/filesystems/fuse.txt
           https://www.freedesktop.org/wiki/Software/systemd/APIFileSystems
#journalctl -b -u systemd-sysusers.service
-- Logs begin at Thu 2022-03-17 21:45:33 EET, end at Thu 2022-03-17 21:48:11 EET. --
-- No entries --
# systemctl status systemd-sysusers.service
● systemd-sysusers.service - Create System Users
   Loaded: loaded (/usr/lib/systemd/system/systemd-sysusers.service; static; vendor preset: disabled)
   Active: failed (Result: start-limit-hit)
Condition: start condition failed at Thu 2022-03-17 21:45:41 EET; 2min 30s ago
           └─ ConditionNeedsUpdate=/etc was not met
     Docs: man:sysusers.d(5)
           man:systemd-sysusers.service(8)
#journalctl -b -u systemd-hwdb-update.service
-- Logs begin at Thu 2022-03-17 21:45:33 EET, end at Thu 2022-03-17 21:48:11 EET. --
-- No entries --
# systemctl status systemd-hwdb-update.service
● systemd-hwdb-update.service - Rebuild Hardware Database
   Loaded: loaded (/usr/lib/systemd/system/systemd-hwdb-update.service; static; vendor preset: disabled)
   Active: failed (Result: start-limit-hit)
Condition: start condition failed at Thu 2022-03-17 21:45:41 EET; 2min 30s ago
           ├─ ConditionNeedsUpdate=/etc was not met
           └─ ConditionDirectoryNotEmpty=|/etc/udev/hwdb.d was not met
     Docs: man:hwdb(7)
           man:systemd-hwdb(8)

The odd thing: no logs except the first failed boot (I rebooted several times, journal is volatile):
# grep -E "loadmodules|sys-fs-fuse-connections|systemd-sysusers|systemd-hwdb-update" /var/log/messages | grep -v 07:55:12
#

touch /.autorelabel && reboot did not help. Nor did booting with selinux in permissive mode.

Comment 3 GV 2022-03-17 19:59:56 UTC
Created attachment 1866504 [details]
Very blurry image

Comment 4 David Tardon 2022-03-25 15:23:07 UTC
*** Bug 2068495 has been marked as a duplicate of this bug. ***

Comment 5 Renaud Métrich 2022-03-25 15:33:27 UTC
Full analysis in BZ #2068495.

Comment 6 David Tardon 2022-04-12 14:45:19 UTC
I cannot reproduce this, but I am pretty sure we need https://github.com/systemd/systemd/pull/21030 .

Comment 7 David Tardon 2022-04-28 11:13:11 UTC
*** Bug 2076860 has been marked as a duplicate of this bug. ***

Comment 8 David Tardon 2022-05-05 08:35:15 UTC
*** Bug 2081739 has been marked as a duplicate of this bug. ***

Comment 10 Renaud Métrich 2022-05-05 13:52:05 UTC
The root cause analysis was somehow done in BZ #2068495 (along with a reproducer).

Comment 11 David Tardon 2022-05-10 17:09:10 UTC
Test rpms are available at https://people.redhat.com/dtardon/systemd/bz2065322-start-limit-hit/ .

Comment 12 GV 2022-05-11 07:14:11 UTC
(In reply to David Tardon from comment #11)
> Test rpms are available at
> https://people.redhat.com/dtardon/systemd/bz2065322-start-limit-hit/ .

The issue seems to be fixed (tried several reboots and everything was fine). Thank you!

Comment 16 Plumber Bot 2022-06-09 09:22:15 UTC
fix merged to github master branch -> https://github.com/redhat-plumbers/systemd-rhel8/pull/270

Comment 22 David Tardon 2022-08-08 09:25:11 UTC
*** Bug 2108529 has been marked as a duplicate of this bug. ***

Comment 23 David Tardon 2022-08-10 14:29:24 UTC
*** Bug 2117158 has been marked as a duplicate of this bug. ***

Comment 24 Mateusz Kusiak 2022-08-11 09:11:04 UTC
Hello,
will this change affect RHEL8.6?

Comment 25 David Tardon 2022-08-15 08:47:51 UTC
(In reply to Mateusz Kusiak from comment #24)
> Hello,
> will this change affect RHEL8.6?

There's a 8.6.z clone of this (bug 2086553) if that's what you mean.

Comment 28 errata-xmlrpc 2022-11-08 10:49:54 UTC
Since the problem described in this bug report should be
resolved in a recent advisory, it has been closed with a
resolution of ERRATA.

For information on the advisory (systemd bug fix and enhancement update), and where to find the updated
files, follow the link below.

If the solution does not work for you, open a new bug report.

https://access.redhat.com/errata/RHBA-2022:7727