Bug 2071306 - dracut-shutdown.service failed due to dracut-initramfs-restore errors opening initrd when rebooting or shutting down with dracut-056-1.fc36
Summary: dracut-shutdown.service failed due to dracut-initramfs-restore errors opening...
Keywords:
Status: CLOSED EOL
Alias: None
Product: Fedora
Classification: Fedora
Component: dracut
Version: 36
Hardware: Unspecified
OS: Unspecified
unspecified
unspecified
Target Milestone: ---
Assignee: dracut-maint-list
QA Contact: Fedora Extras Quality Assurance
URL:
Whiteboard:
Depends On:
Blocks:
TreeView+ depends on / blocked
 
Reported: 2022-04-02 19:57 UTC by Matt Fagnani
Modified: 2023-05-25 15:55 UTC (History)
6 users (show)

Fixed In Version:
Doc Type: ---
Doc Text:
Clone Of:
Environment:
Last Closed: 2023-05-25 15:55:59 UTC
Type: Bug
Embargoed:


Attachments (Terms of Use)

Description Matt Fagnani 2022-04-02 19:57:45 UTC
Description of problem:

I updated a Fedora 36 KDE Plasma installation on 2022-3-31 with updates-testing using sudo dnf offline-upgrade download
sudo dnf offline-upgrade reboot

The update contained dracut-056-1.fc36.x86_64. dracut-shutdown.service failed due to dracut-initramfs-restore errors opening initrd when rebooting or shutting down with dracut-056-1.fc36. dracut-initramfs-restore repeatedly tried to open /boot/cf0bf479bcf04633b727cb244f663cd7/5.17.1-300.fc36.x86_64/initrd which didn't exist.

Mar 31 18:40:57 dracut-initramfs-restore[1535]: ERROR: src/skipcpio/skipcpio.c:91:main(): Cannot open file '/boot/cf0bf479bcf04633b727cb244f663cd7/5.17.1-300.fc36.x86_64/initrd'
Mar 31 18:40:57 lvm[1522]:   pvscan[1522] PV /dev/sda3 online.
Mar 31 18:40:57 dracut-initramfs-restore[1536]: cpio: premature end of archive
Mar 31 18:40:57 dracut-initramfs-restore[1537]: ERROR: src/skipcpio/skipcpio.c:91:main(): Cannot open file '/boot/cf0bf479bcf04633b727cb244f663cd7/5.17.1-300.fc36.x86_64/initrd'
Mar 31 18:40:57 systemd[1]: Started plymouth-reboot.service - Show Plymouth Reboot Screen.
Mar 31 18:40:57 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=plymouth-reboot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 31 18:40:57 kernel: audit: type=1130 audit(1648766457.464:453): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=plymouth-reboot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 31 18:40:57 dracut-initramfs-restore[1538]: gzip: stdin: unexpected end of file
Mar 31 18:40:57 systemd[1]: lvm2-pvscan@8:3.service: Deactivated successfully.
Mar 31 18:40:57 dracut-initramfs-restore[1539]: cpio: premature end of archive
Mar 31 18:40:57 dracut-initramfs-restore[1540]: ERROR: src/skipcpio/skipcpio.c:91:main(): Cannot open file '/boot/cf0bf479bcf04633b727cb244f663cd7/5.17.1-300.fc36.x86_64/initrd'
Mar 31 18:40:57 dracut-initramfs-restore[1541]: bzcat: Compressed file ends unexpectedly;
Mar 31 18:40:57 dracut-initramfs-restore[1541]:         perhaps it is corrupted?  *Possible* reason follows.
Mar 31 18:40:57 dracut-initramfs-restore[1541]: bzcat: Inappropriate ioctl for device
Mar 31 18:40:57 dracut-initramfs-restore[1541]:         Input file = (stdin), output file = (stdout)
Mar 31 18:40:57 dracut-initramfs-restore[1541]: It is possible that the compressed file(s) have become corrupted.
Mar 31 18:40:57 dracut-initramfs-restore[1541]: You can use the -tvv option to test integrity of such files.
Mar 31 18:40:57 dracut-initramfs-restore[1541]: You can use the `bzip2recover' program to attempt to recover
Mar 31 18:40:57 dracut-initramfs-restore[1541]: data from undamaged sections of corrupted files.
Mar 31 18:40:57 dracut-initramfs-restore[1542]: cpio: premature end of archive
Mar 31 18:40:57 dracut-initramfs-restore[1543]: ERROR: src/skipcpio/skipcpio.c:91:main(): Cannot open file '/boot/cf0bf479bcf04633b727cb244f663cd7/5.17.1-300.fc36.x86_64/initrd'
Mar 31 18:40:57 dracut-initramfs-restore[1544]: xzcat: (stdin): File format not recognized
Mar 31 18:40:57 dracut-initramfs-restore[1545]: cpio: premature end of archive
Mar 31 18:40:57 dracut-initramfs-restore[1546]: ERROR: src/skipcpio/skipcpio.c:91:main(): Cannot open file '/boot/cf0bf479bcf04633b727cb244f663cd7/5.17.1-300.fc36.x86_64/initrd'
Mar 31 18:40:57 dracut-initramfs-restore[1548]: cpio: premature end of archive
Mar 31 18:40:57 dracut-initramfs-restore[1549]: ERROR: src/skipcpio/skipcpio.c:91:main(): Cannot open file '/boot/cf0bf479bcf04633b727cb244f663cd7/5.17.1-300.fc36.x86_64/initrd'
Mar 31 18:40:57 kernel: audit: type=1131 audit(1648766457.481:454): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=lvm2-pvscan@8:3 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 31 18:40:57 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=lvm2-pvscan@8:3 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 31 18:40:57 systemd[1]: Stopped lvm2-pvscan@8:3.service - LVM event activation on device 8:3.
Mar 31 18:40:57 systemd[1]: systemd-random-seed.service: Deactivated successfully.
Mar 31 18:40:57 dracut-initramfs-restore[1550]: lzop: <stdin>: not a lzop file
Mar 31 18:40:57 dracut-initramfs-restore[1551]: cpio: premature end of archive
Mar 31 18:40:57 systemd[1]: Stopped systemd-random-seed.service - Load/Save Random Seed.
Mar 31 18:40:57 dracut-initramfs-restore[1552]: ERROR: src/skipcpio/skipcpio.c:91:main(): Cannot open file '/boot/cf0bf479bcf04633b727cb244f663cd7/5.17.1-300.fc36.x86_64/initrd'
Mar 31 18:40:57 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-random-seed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 31 18:40:57 kernel: audit: type=1131 audit(1648766457.489:455): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-random-seed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 31 18:40:57 systemd[1]: Removed slice system-lvm2\x2dpvscan.slice - Slice /system/lvm2-pvscan.
Mar 31 18:40:57 dracut-initramfs-restore[1553]: zstd: /*stdin*\: unexpected end of file
Mar 31 18:40:57 dracut-initramfs-restore[1554]: cpio: premature end of archive
Mar 31 18:40:57 dracut-initramfs-restore[1520]: Unpacking of /boot/cf0bf479bcf04633b727cb244f663cd7/5.17.1-300.fc36.x86_64/initrd to /run/initramfs failed
Mar 31 18:40:57 systemd[1]: dracut-shutdown.service: Control process exited, code=exited, status=1/FAILURE
Mar 31 18:40:57 systemd[1]: dracut-shutdown.service: Failed with result 'exit-code'.
Mar 31 18:40:57 systemd[1]: Stopped dracut-shutdown.service - Restore /run/initramfs on shutdown.
Mar 31 18:40:57 systemd[1]: dracut-shutdown.service: Triggering OnFailure= dependencies.
Mar 31 18:40:57 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dracut-shutdown comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed'
Mar 31 18:40:57 kernel: audit: type=1131 audit(1648766457.511:456): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dracut-shutdown comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=failed'
Mar 31 18:40:57 systemd[1]: dbus-broker.service: Deactivated successfully.
Mar 31 18:40:57 systemd[1]: Stopped dbus-broker.service - D-Bus System Message Bus.
Mar 31 18:40:57 kernel: audit: type=1131 audit(1648766457.519:457): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dbus-broker comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 31 18:40:57 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dbus-broker comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 31 18:40:57 systemd[1]: systemd-logind.service: Deactivated successfully.
Mar 31 18:40:57 systemd[1]: Stopped systemd-logind.service - User Login Management.
Mar 31 18:40:57 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-logind comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 31 18:40:57 kernel: audit: type=1131 audit(1648766457.532:458): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=systemd-logind comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 31 18:40:57 audit: BPF prog-id=0 op=UNLOAD
Mar 31 18:40:57 kernel: audit: type=1334 audit(1648766457.534:459): prog-id=0 op=UNLOAD
Mar 31 18:40:57 audit: BPF prog-id=0 op=UNLOAD
Mar 31 18:40:57 kernel: audit: type=1334 audit(1648766457.536:460): prog-id=0 op=UNLOAD
Mar 31 18:40:57 systemd[1]: Removed slice user.slice - User and Session Slice.
Mar 31 18:40:57 systemd[1]: dbus.socket: Deactivated successfully.
Mar 31 18:40:57 systemd[1]: Closed dbus.socket - D-Bus System Message Bus Socket.
Mar 31 18:40:57 systemd[1]: Starting dracut-shutdown-onfailure.service - Service executing upon dracut-shutdown failure to perform cleanup...
Mar 31 18:40:57 systemd[1]: plymouth-switch-root-initramfs.service - Tell Plymouth To Jump To initramfs was skipped because of a failed condition check (ConditionPathExists=/run/initramfs/bin/sh).
Mar 31 18:40:57 systemd[1]: dracut-shutdown-onfailure.service: Deactivated successfully.
Mar 31 18:40:57 systemd[1]: Finished dracut-shutdown-onfailure.service - Service executing upon dracut-shutdown failure to perform cleanup.
Mar 31 18:40:57 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dracut-shutdown-onfailure comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 31 18:40:57 kernel: audit: type=1130 audit(1648766457.564:461): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dracut-shutdown-onfailure comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Mar 31 18:40:57 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:init_t:s0 msg='unit=dracut-shutdown-onfailure comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'

Version-Release number of selected component (if applicable):
dracut-056-1.fc36.x86_64
kernel-5.17.1-300.fc36.x86_64

How reproducible:
dracut-shutdown.service failed and dracut-initramfs-restore errors appeared on each of about 20 reboots and shutdowns with dracut-056-1.fc36.x86_64, but they didn't appear with dracut-055-8.fc36.1.x86_64 and earlier.

Steps to Reproduce:
1. Boot a Fedora 36 KDE Plasma installation
2. Log in to Plasma on Wayland
3. Start konsole
4. Update to dracut-056-1.fc36 if it isn't already installed which I did with
sudo dnf offline-upgrade download
sudo dnf offline-upgrade reboot
5. Reboot or shut down if dracut-056-1.fc36 is already installed

Actual results:
dracut-shutdown.service failed due to dracut-initramfs-restore errors opening initrd when rebooting or shutting down with dracut-056-1.fc36

Expected results:
dracut-shutdown.service wouldn't fail and dracut-initramfs-restore errors opening initrd wouldn't happen when rebooting or shutting down with dracut-056-1.fc36

Additional info:
The system still rebooted or shut down. I downgraded to dracut-055-8.fc36.1.x86_64, and the problem didn't happen with dracut-055-8.fc36.1.x86_64.

The change at https://github.com/dracutdevs/dracut/pull/1634 involves dracut-initramfs-restore and /boot/$MACHINE_ID which looks like the erroneous initrd path in the error message 
dracut-initramfs-restore[1535]: ERROR: src/skipcpio/skipcpio.c:91:main(): Cannot open file '/boot/cf0bf479bcf04633b727cb244f663cd7/5.17.1-300.fc36.x86_64/initrd'

Comment 1 Matt Fagnani 2023-04-01 16:50:57 UTC
This problem doesn't happen now in a F38 KDE Plasma installation with dracut-059-2.fc38. The commit at https://github.com/dracutdevs/dracut/commit/481b87fa7a82be54663071ad9ad76c34e378ddc7 might've fixed this problem.

Comment 2 Ben Cotton 2023-04-25 16:58:49 UTC
This message is a reminder that Fedora Linux 36 is nearing its end of life.
Fedora will stop maintaining and issuing updates for Fedora Linux 36 on 2023-05-16.
It is Fedora's policy to close all bug reports from releases that are no longer
maintained. At that time this bug will be closed as EOL if it remains open with a
'version' of '36'.

Package Maintainer: If you wish for this bug to remain open because you
plan to fix it in a currently maintained version, change the 'version' 
to a later Fedora Linux version. Note that the version field may be hidden.
Click the "Show advanced fields" button if you do not see it.

Thank you for reporting this issue and we are sorry that we were not 
able to fix it before Fedora Linux 36 is end of life. If you would still like 
to see this bug fixed and are able to reproduce it against a later version 
of Fedora Linux, you are encouraged to change the 'version' to a later version
prior to this bug being closed.

Comment 3 Ludek Smid 2023-05-25 15:55:59 UTC
Fedora Linux 36 entered end-of-life (EOL) status on 2023-05-16.

Fedora Linux 36 is no longer maintained, which means that it
will not receive any further security or bug fix updates. As a result we
are closing this bug.

If you can reproduce this bug against a currently maintained version of Fedora Linux
please feel free to reopen this bug against that version. Note that the version
field may be hidden. Click the "Show advanced fields" button if you do not see
the version field.

If you are unable to reopen this bug, please file a new report against an
active release.

Thank you for reporting this bug and we are sorry it could not be fixed.


Note You need to log in before you can comment on or make changes to this bug.