[systemd-devel] timed out waiting for device dev-disk-by\x2duuid

[Chris Murphy]

Two device Btrfs volume, with one device missing (simulated) will not boot, even with rootflags=degraded set which is currently required to enable Btrfs degraded mounts. Upon reaching a dracut shell after basic.target fails with time out, I can mount -o subvol=root,degraded and exit and continue boot normally with just the single device.

The problem seems to be that systemd (udev?) is not finding the volume by uuid for some reason, and therefore not attempting to mount it. But I don't know why it can't find it, or even how the find by uuid mechanism works this early in boot. So I'm not sure if this is a systemd or udev bug, or a dracut, or kernel bug.

The problem happens with systemd 208-9.fc20 with kernel 3.11.10-301.fc20, and systemd 212-4.fc21 and kernel 3.15.0-0.rc5.git0.1.fc21.


Details:

[    0.000000] localhost.localdomain kernel: Command line: BOOT_IMAGE=/boot/vmlinuz-3.11.10-301.fc20.x86_64 root=UUID=c27871d6-5dbe-4d32-9ba9-03b425076da4 ro rootflags=subvol=root,degraded elevator=deadline systemd.log_level=debug systemd.log_target=kmsg log_buf_len=1M


Partial of rdsosreport.txt from the above boot which has systemd logging set to debug.


[    1.466282]  systemd[1]: Reached target Basic System.
[    1.466362]  systemd[1]: Accepted connection on private bus.
[    1.466808]  systemd[1]: Got D-Bus request: org.freedesktop.systemd1.Agent.Released() on /org/freedesktop/systemd1/agent
[    1.466898]  systemd[1]: Got D-Bus request: org.freedesktop.DBus.Local.Disconnected() on /org/freedesktop/DBus/Local
[   91.089693]  systemd[1]: Job dev-disk-by\x2duuid-c27871d6\x2d5dbe\x2d4d32\x2d9ba9\x2d03b425076da4.device/start timed out.
[   91.098727]  systemd[1]: Job dev-disk-by\x2duuid-c27871d6\x2d5dbe\x2d4d32\x2d9ba9\x2d03b425076da4.device/start finished, result=timeout
[   91.099205]  systemd[1]: Timed out waiting for device dev-disk-by\x2duuid-c27871d6\x2d5dbe\x2d4d32\x2d9ba9\x2d03b425076da4.device.
[   91.099227]  systemd[1]: Job sysroot.mount/start finished, result=dependency
[   91.101537]  systemd[1]: Dependency failed for /sysroot.
[   91.101548]  systemd[1]: Job initrd-root-fs.target/start finished, result=dependency
[   91.102482]  systemd[1]: Dependency failed for Initrd Root File System.
[   91.102497]  systemd[1]: Job initrd-parse-etc.service/start finished, result=dependency
[   91.104125]  systemd[1]: Dependency failed for Reload Configuration from the Real Root.
[   91.104135]  systemd[1]: Triggering OnFailure= dependencies of initrd-parse-etc.service.
[   91.104141]  systemd[1]: Trying to enqueue job emergency.target/start/replace
[   91.104165]  systemd[1]: Installed new job emergency.target/start as 41
[   91.104170]  systemd[1]: Installed new job emergency.service/start as 42
[   91.104174]  systemd[1]: Installed new job sysinit.target/stop as 45
[   91.104179]  systemd[1]: Installed new job basic.target/stop as 46
[   91.104183]  systemd[1]: Job initrd.target/start finished, result=canceled
[   91.104196]  systemd[1]: Closed jobs progress timerfd.
[   91.104200]  systemd[1]: Installed new job initrd.target/stop as 47
[   91.104205]  systemd[1]: Job dracut-mount.service/start finished, result=canceled
[   91.104272]  systemd[1]: Installed new job dracut-mount.service/stop as 48
[   91.104278]  systemd[1]: Job dracut-pre-pivot.service/start finished, result=canceled
[   91.104306]  systemd[1]: Installed new job dracut-pre-pivot.service/stop as 49
[   91.104310]  systemd[1]: Enqueued job emergency.target/start as 41
[   91.104317]  systemd[1]: Triggering OnFailure= dependencies of initrd-root-fs.target.
[   91.104322]  systemd[1]: Trying to enqueue job emergency.target/start/isolate
[   91.104365]  systemd[1]: Job initrd.target/stop finished, result=canceled
[   91.104371]  systemd[1]: Job dracut-mount.service/stop finished, result=canceled
[   91.104376]  systemd[1]: Job dracut-pre-pivot.service/stop finished, result=canceled
[   91.104382]  systemd[1]: Merged into installed job emergency.target/start as 41
[   91.104387]  systemd[1]: Merged into installed job emergency.service/start as 42
[   91.104391]  systemd[1]: Merged into installed job sysinit.target/stop as 45
[   91.104396]  systemd[1]: Merged into installed job basic.target/stop as 46
[   91.104400]  systemd[1]: Installed new job local-fs.target/stop as 61
[   91.104406]  systemd[1]: Installed new job system.slice/stop as 62
[   91.104411]  systemd[1]: Installed new job slices.target/stop as 63
[   91.104415]  systemd[1]: Installed new job -.slice/stop as 64
[   91.104419]  systemd[1]: Installed new job sockets.target/stop as 65
[   91.104424]  systemd[1]: Installed new job systemd-journald.service/stop as 66
[   91.104429]  systemd[1]: Installed new job systemd-udevd-kernel.socket/stop as 67
[   91.104434]  systemd[1]: Installed new job systemd-udevd-control.socket/stop as 68
[   91.104439]  systemd[1]: Installed new job systemd-udevd.service/stop as 69
[   91.104443]  systemd[1]: Installed new job swap.target/stop as 70
[   91.104448]  systemd[1]: Installed new job systemd-tmpfiles-setup-dev.service/stop as 71
[   91.104453]  systemd[1]: Installed new job kmod-static-nodes.service/stop as 72
[   91.104457]  systemd[1]: Installed new job systemd-udev-trigger.service/stop as 73
[   91.104461]  systemd[1]: Installed new job plymouth-start.service/stop as 74
[   91.104465]  systemd[1]: Installed new job systemd-ask-password-plymouth.path/stop as 75
[   91.104469]  systemd[1]: Installed new job timers.target/stop as 76
[   91.104473]  systemd[1]: Installed new job paths.target/stop as 77
[   91.104477]  systemd[1]: Job initrd-fs.target/start finished, result=canceled
[   91.104482]  systemd[1]: Installed new job initrd-fs.target/stop as 78
[   91.104486]  systemd[1]: Installed new job dracut-initqueue.service/stop as 79
[   91.104490]  systemd[1]: Enqueued job emergency.target/start as 41
[   91.104530]  systemd[1]: Collecting dracut-pre-pivot.service
[   91.104535]  systemd[1]: Collecting initrd-cleanup.service
[   91.104558]  systemd[1]: Collecting dracut-mount.service
[   91.104562]  systemd[1]: Collecting initrd.target
[   91.104645]  systemd[1]: Collecting rescue.target
[   91.104649]  systemd[1]: Collecting rescue.service
[   91.104654]  systemd[1]: Collecting dracut-cmdline.service
[   91.104658]  systemd[1]: Collecting dracut-pre-trigger.service
[   91.104662]  systemd[1]: Collecting dracut-pre-udev.service
[   91.104666]  systemd[1]: Collecting cryptsetup.target
[   91.104712]  systemd[1]: Stopping dracut initqueue hook...
[   91.104718]  systemd[1]: dracut-initqueue.service changed exited -> dead
[   91.104729]  systemd[1]: Job dracut-initqueue.service/stop finished, result=done
[   91.104736]  systemd[1]: Stopped dracut initqueue hook.
[   91.104776]  systemd[1]: Job initrd-fs.target/stop finished, result=done
[   91.104783]  systemd[1]: Stopped target Initrd File Systems.
[   91.104791]  systemd[1]: Stopping Basic System.
[   91.104796]  systemd[1]: basic.target changed active -> dead
[   91.104806]  systemd[1]: Job basic.target/stop finished, result=done
[   91.104812]  systemd[1]: Stopped target Basic System.


The complete rdsosreport.txt

https://drive.google.com/file/d/0B_2Asp8DGjJ9MWctT3JfdlQ1S0k/edit?usp=sharing


Chris Murphy