Bug#830770: mdadm: initramfs broken

Jamie Heilman jamie at audible.transient.net
Tue Jul 12 09:05:58 UTC 2016 

Dimitri John Ledkov wrote:
> Previously, local-top script was used to wait a little and assemble
> all arrays.  Now, udev rules are used to incrementally assemble
> arrays.  In the mean time local-block "main-loop" scripts are used
> to poll and activate LVM volumes.  In case of incomplete arrays,
> local-block/mdadm script is used to force start incomplete mdadm
> arrays after 2/3rds of iterations.

I suspect this is semi-related to 784070, or atleast the changes that
begat 784070.
 
> Is udev available in your initramfs?

Yes.

> I shall try to recreate your setup and debug this further.

I had a little time to poke at this tonight, with 3.4-1 (which works)
I notice this in my kernel ring buffer:

[    3.273063] systemd-udevd[812]: Process '/sbin/lvm pvscan --cache --activate ay --major 8 --minor 1' failed with exit code 5.
[    3.273261] systemd-udevd[807]: Process '/sbin/lvm pvscan --cache --activate ay --major 8 --minor 17' failed with exit code 5.
[    3.362754] md: raid1 personality registered for level 1
[    3.378702] md: md_d0 stopped.
[    3.379687] md: bind<sdb>
[    3.380270] md: bind<sda>
[    3.389584] md/raid1:md_d0: active with 2 out of 2 mirrors
[    3.390009] md_d0: detected capacity change from 0 to 1000204746752
[    3.390658]  md_d0: p1 p2

I'm not real sure what those pvscan failures are all about, I'll
need to look into that further, but it doesn't seem to impact the
end result.  I've attached the initramfs debug output as initramfs.debug-1.

Now compare this with 3.4-2 and the messages in the ring bufffer...

[    3.145170] systemd-udevd[799]: Process '/sbin/mdadm -If sdb1 --path pci-0000:00:1f.2-ata-2' failed with exit code 1.
[    3.145776] systemd-udevd[802]: Process '/sbin/mdadm -If sdb2 --path pci-0000:00:1f.2-ata-2' failed with exit code 1.
[    3.146243] systemd-udevd[811]: Process '/sbin/mdadm -If sda2 --path pci-0000:00:1f.2-ata-1' failed with exit code 1.
[    3.146411] systemd-udevd[810]: Process '/sbin/mdadm -If sda1 --path pci-0000:00:1f.2-ata-1' failed with exit code 1.
[    3.290217] md: raid1 personality registered for level 1
[    3.312503] md: linear personality registered for level -1
[    3.315720] md: raid0 personality registered for level 0
[    3.319781] xor: measuring software checksum speed
[    3.330003]    prefetch64-sse: 12276.000 MB/sec
[    3.340002]    generic_sse: 10840.000 MB/sec
[    3.340342] xor: using function: prefetch64-sse (12276.000 MB/sec)
[    3.360013] raid6: sse2x1   gen()  4957 MB/s
[    3.377003] raid6: sse2x1   xor()  4718 MB/s
[    3.394011] raid6: sse2x2   gen()  5503 MB/s
[    3.411008] raid6: sse2x2   xor()  5937 MB/s
[    3.428007] raid6: sse2x4   gen()  8339 MB/s
[    3.445005] raid6: sse2x4   xor()  6890 MB/s
[    3.445327] raid6: using algorithm sse2x4 gen() 8339 MB/s
[    3.445821] raid6: .... xor() 6890 MB/s, rmw enabled
[    3.446545] raid6: using ssse3x2 recovery algorithm
[    3.453001] md: raid6 personality registered for level 6
[    3.453338] md: raid5 personality registered for level 5
[    3.453813] md: raid4 personality registered for level 4
[    3.458434] md: raid10 personality registered for level 10

and then much later the array finally comes on line but that's after
I've manually forced the matter.  Interestingly, manually running
 udevadm trigger -c add 
worked as well as
 mdadm --assemble --scan

As an aside, I'm annoyed by the change to force load all the raid
modules at boot.  That's wasted kernel memory and loading
functionality I have no use for in the initial boot phase.  My
initramfs modules file is configured with exactly the modules needed
to bring up my system.  It seems like the initramfs just keeps getting
more inelegant with time, we went from stuffing in more modules than I
needed and bloating the initramfs uselessly (which 3.4-1 already did)
to now force loading all those modules as well despite having no need
for them on boot.  But I digress.

What I think is interesting, is that the calls to mdadm -I are passing
the partition devices rather than the drive devices, and those
partitions aren't md devices ... but maybe it passes the drive devices
too and that just doesn't get reported.  I dunno, I haven't tried
debugging the udev lifecycle yet (nor have I even figured out how to
go about that).  Anyway, I've attached the initramfs debug output for
3.4-2 as well.  You can see it fails in a loop for a bit, then kicks
me out to shell.  To get things rolling again I did:
udevadm trigger -c add
(waited a second)
. /scripts/functions
run_scripts /scripts/local-top
then exited the shell.

I wouldn't be stunned if this problem mostly stemmed from the fact
that /scripts/init-top/udev executes prior to loading kernel modules.
It might be interesting to see what happens if we toss
 udevadm trigger --action=add
 udevadm settle || true
into a script in /scripts/init-premount ... 

-- 
Jamie Heilman                     http://audible.transient.net/~jamie/
-------------- next part --------------
+ [ -z  ]
+ BOOT=local
+ [ -n  ]
+ resume=UUID=cea15be1-79cc-4633-9622-f8b7e29f9e10
+ maybe_break top
+ run_scripts /scripts/init-top
+ initdir=/scripts/init-top
+ [ ! -d /scripts/init-top ]
+ shift
+ . /scripts/init-top/ORDER
+ /scripts/init-top/all_generic_ide
+ [ -e /conf/param.conf ]
+ /scripts/init-top/blacklist
+ [ -e /conf/param.conf ]
+ /scripts/init-top/keymap
+ [ -e /conf/param.conf ]
+ /scripts/init-top/udev
calling: trigger
device-enumerator: scan all dirs
  device-enumerator: scanning /sys/bus
  device-enumerator: scanning /sys/class
calling: settle
+ [ -e /conf/param.conf ]
+ maybe_break modules
+ [ n != y ]
+ log_begin_msg Loading essential drivers
+ _log_msg Begin: Loading essential drivers ... 
+ [ n = y ]
+ printf Begin: Loading essential drivers ... 
Begin: Loading essential drivers ... + [ -n  ]
+ load_modules
+ [ -e /conf/modules ]
+ read m
+ cat /conf/modules
+ [ -z md_mod ]
+ printf %.1s md_mod
+ com=m
+ [ m = # ]
+ modprobe md_mod
+ read m
+ [ -z raid1 ]
+ printf %.1s raid1
+ com=r
+ [ r = # ]
+ modprobe raid1
+ read m
+ [ -z dm_mod ]
+ printf %.1s dm_mod
+ com=d
+ [ d = # ]
+ modprobe dm_mod
+ read m
+ [ -z xfs ]
+ printf %.1s xfs
+ com=x
+ [ x = # ]
+ modprobe xfs
+ read m
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ [  ]
+ maybe_break premount
+ [ n != y ]
+ log_begin_msg Running /scripts/init-premount
+ _log_msg Begin: Running /scripts/init-premount ... 
+ [ n = y ]
+ printf Begin: Running /scripts/init-premount ... 
Begin: Running /scripts/init-premount ... + run_scripts /scripts/init-premount
+ initdir=/scripts/init-premount
+ [ ! -d /scripts/init-premount ]
+ return
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ maybe_break mount
+ log_begin_msg Mounting root file system
+ _log_msg Begin: Mounting root file system ... 
+ [ n = y ]
+ printf Begin: Mounting root file system ... 
Begin: Mounting root file system ... + . /scripts/local
+ . /scripts/nfs
+ . /scripts/local
+ parse_numeric /dev/mapper/S-root
+ return
+ maybe_break mountroot
+ mount_top
+ local_top
+ [  != yes ]
+ [ n != y ]
+ log_begin_msg Running /scripts/local-top
+ _log_msg Begin: Running /scripts/local-top ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-top ... 
Begin: Running /scripts/local-top ... + run_scripts /scripts/local-top
+ initdir=/scripts/local-top
+ [ ! -d /scripts/local-top ]
+ shift
+ . /scripts/local-top/ORDER
+ /scripts/local-top/cryptopensc
+ [ -e /conf/param.conf ]
+ /scripts/local-top/mdadm
Begin: Assembling all MD arrays ... mdadm: /dev/md/d0 has been started with 2 drives.
Success: assembled all arrays.
done.
calling: settle
+ [ -e /conf/param.conf ]
+ /scripts/local-top/lvm2
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
+ [ -e /conf/param.conf ]
+ /scripts/local-top/cryptroot
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ local_top_used=yes
+ mount_premount
+ local_premount
+ [  != yes ]
+ [ n != y ]
+ log_begin_msg Running /scripts/local-premount
+ _log_msg Begin: Running /scripts/local-premount ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-premount ... 
Begin: Running /scripts/local-premount ... + run_scripts /scripts/local-premount
+ initdir=/scripts/local-premount
+ [ ! -d /scripts/local-premount ]
+ shift
+ . /scripts/local-premount/ORDER
+ /scripts/local-premount/resume
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ local_premount_used=yes
+ mountroot
+ local_mount_root
+ local_top
+ [ yes != yes ]
+ local_top_used=yes
+ local_device_setup /dev/mapper/S-root root
+ local dev_id=/dev/mapper/S-root
+ local name=root
+ local real_dev
+ wait_for_udev 10
+ command -v udevadm
+ udevadm settle --timeout=10
calling: settle
+ [ -n  ]
+ [ /mapper/S-root = /dev/mapper/S-root ]
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ echo /dev/mapper/S-root
+ real_dev=/dev/mapper/S-root
+ get_fstype /dev/mapper/S-root
+ local FS FSTYPE FSSIZE RET
+ FS=/dev/mapper/S-root
+ FSTYPE=unknown
+ fstype /dev/mapper/S-root
+ eval FSTYPE=xfs FSSIZE=8589934592
+ FSTYPE=xfs FSSIZE=8589934592
+ [ xfs = unknown ]
+ RET=0
+ [ -z xfs ]
+ echo xfs
+ return 0
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ echo /dev/mapper/S-root
+ real_dev=/dev/mapper/S-root
+ get_fstype /dev/mapper/S-root
+ local FS FSTYPE FSSIZE RET
+ FS=/dev/mapper/S-root
+ FSTYPE=unknown
+ fstype /dev/mapper/S-root
+ eval FSTYPE=xfs FSSIZE=8589934592
+ FSTYPE=xfs FSSIZE=8589934592
+ [ xfs = unknown ]
+ RET=0
+ [ -z xfs ]
+ echo xfs
+ return 0
+ DEV=/dev/mapper/S-root
+ ROOT=/dev/mapper/S-root
+ [ -z  ]
+ get_fstype /dev/mapper/S-root
+ local FS FSTYPE FSSIZE RET
+ FS=/dev/mapper/S-root
+ FSTYPE=unknown
+ fstype /dev/mapper/S-root
+ eval FSTYPE=xfs FSSIZE=8589934592
+ FSTYPE=xfs FSSIZE=8589934592
+ [ xfs = unknown ]
+ RET=0
+ [ -z xfs ]
+ echo xfs
+ return 0
+ FSTYPE=xfs
+ local_premount
+ [ yes != yes ]
+ local_premount_used=yes
+ [ y = y ]
+ roflag=-r
+ modprobe xfs
+ checkfs /dev/mapper/S-root root xfs
+ _checkfs_once /dev/mapper/S-root root xfs
+ DEV=/dev/mapper/S-root
+ NAME=root
+ TYPE=xfs
+ [ root = / ]
+ FSCK_LOGFILE=/run/initramfs/fsck.log
+ FSCK_STAMPFILE=/run/initramfs/fsck-root
+ [ xfs = auto ]
+ FSCKCODE=0
+ command -v fsck
+ [ n = y ]
+ [ n = y ]
+ force=
+ [  = y ]
+ [  = n ]
+ fix=-a
+ spinner=
+ [ -z y ]
+ [ n = n ]
+ log_begin_msg Will now check root file system
+ _log_msg Begin: Will now check root file system ... 
+ [ n = y ]
+ printf Begin: Will now check root file system ... 
Begin: Will now check root file system ... + logsave -a -s /run/initramfs/fsck.log fsck -a -V -t xfs /dev/mapper/S-root
fsck from util-linux 2.28
[/sbin/fsck.xfs (1) -- /dev/mapper/S-root] fsck.xfs -a /dev/mapper/S-root 
/sbin/fsck.xfs: XFS file system.
+ FSCKCODE=0
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ [ 0 -eq 32 ]
+ [ 0 -eq 4 ]
+ [ 0 -gt 1 ]
+ 
+ return 0
+ [ xfs != unknown ]
+ mount -r -t xfs /dev/mapper/S-root /root
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ read_fstab_entry /usr
+ found=1
+ [ -f /root/etc/fstab ]
+ read MNT_FSNAME MNT_DIR MNT_TYPE MNT_OPTS MNT_FREQ MNT_PASS MNT_JUNK
+ [ / = /usr ]
+ read MNT_FSNAME MNT_DIR MNT_TYPE MNT_OPTS MNT_FREQ MNT_PASS MNT_JUNK
+ [ none = /usr ]
+ read MNT_FSNAME MNT_DIR MNT_TYPE MNT_OPTS MNT_FREQ MNT_PASS MNT_JUNK
+ [ /tmp = /usr ]
+ read MNT_FSNAME MNT_DIR MNT_TYPE MNT_OPTS MNT_FREQ MNT_PASS MNT_JUNK
+ [ /var = /usr ]
+ read MNT_FSNAME MNT_DIR MNT_TYPE MNT_OPTS MNT_FREQ MNT_PASS MNT_JUNK
+ [ /var/tmp = /usr ]
+ read MNT_FSNAME MNT_DIR MNT_TYPE MNT_OPTS MNT_FREQ MNT_PASS MNT_JUNK
+ [ /media/music = /usr ]
+ read MNT_FSNAME MNT_DIR MNT_TYPE MNT_OPTS MNT_FREQ MNT_PASS MNT_JUNK
+ [ /srv/nfs4/music = /usr ]
+ read MNT_FSNAME MNT_DIR MNT_TYPE MNT_OPTS MNT_FREQ MNT_PASS MNT_JUNK
+ [ /sys/fs/cgroup = /usr ]
+ read MNT_FSNAME MNT_DIR MNT_TYPE MNT_OPTS MNT_FREQ MNT_PASS MNT_JUNK
+ [ /sys/kernel/debug = /usr ]
+ read MNT_FSNAME MNT_DIR MNT_TYPE MNT_OPTS MNT_FREQ MNT_PASS MNT_JUNK
+ [ /sys/kernel/security = /usr ]
+ read MNT_FSNAME MNT_DIR MNT_TYPE MNT_OPTS MNT_FREQ MNT_PASS MNT_JUNK
+ return 1
+ mount_bottom
+ local_bottom
+ [ yes = yes ]
+ [ n != y ]
+ log_begin_msg Running /scripts/local-bottom
+ _log_msg Begin: Running /scripts/local-bottom ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-bottom ... 
Begin: Running /scripts/local-bottom ... + run_scripts /scripts/local-bottom
+ initdir=/scripts/local-bottom
+ [ ! -d /scripts/local-bottom ]
+ shift
+ . /scripts/local-bottom/ORDER
+ /scripts/local-bottom/cryptopensc
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ local_premount_used=no
+ local_top_used=no
+ nfs_bottom
+ [  = yes ]
+ [  = yes ]
+ nfs_premount_used=no
+ nfs_top_used=no
+ local_bottom
+ [ no = yes ]
+ [ no = yes ]
+ local_premount_used=no
+ local_top_used=no
+ maybe_break bottom
+ [ n != y ]
+ log_begin_msg Running /scripts/init-bottom
+ _log_msg Begin: Running /scripts/init-bottom ... 
+ [ n = y ]
+ printf Begin: Running /scripts/init-bottom ... 
Begin: Running /scripts/init-bottom ... + run_scripts /scripts/init-bottom
+ initdir=/scripts/init-bottom
+ [ ! -d /scripts/init-bottom ]
+ shift
+ . /scripts/init-bottom/ORDER
+ /scripts/init-bottom/udev
calling: control
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ mount -n -o move /run /root/run
+ validate_init /sbin/init
+ run-init -n /root /sbin/init
+ validate_init /sbin/init
+ run-init -n /root /sbin/init
+ maybe_break init
+ unset debug
+ unset MODPROBE_OPTIONS
+ unset DPKG_ARCH
+ unset ROOTFLAGS
+ unset ROOTFSTYPE
+ unset ROOTDELAY
+ unset ROOT
+ unset IP
+ unset BOOT
+ unset BOOTIF
+ unset UBIMTD
+ unset blacklist
+ unset break
+ unset noresume
+ unset panic
+ unset quiet
+ unset readonly
+ unset resume
+ unset resume_offset
+ unset fastboot
+ unset forcefsck
+ unset fsckfix
+ mount -n -o move /sys /root/sys
+ mount -n -o move /proc /root/proc
+ exec run-init /root /sbin/init
-------------- next part --------------
+ [ -z  ]
+ BOOT=local
+ [ -n  ]
+ resume=UUID=8eefeab4-582f-4afe-bbe2-c92620ec60f5
+ maybe_break top
+ run_scripts /scripts/init-top
+ initdir=/scripts/init-top
+ [ ! -d /scripts/init-top ]
+ shift
+ . /scripts/init-top/ORDER
+ /scripts/init-top/all_generic_ide
+ [ -e /conf/param.conf ]
+ /scripts/init-top/blacklist
+ [ -e /conf/param.conf ]
+ /scripts/init-top/keymap
+ [ -e /conf/param.conf ]
+ /scripts/init-top/udev
calling: trigger
device-enumerator: scan all dirs
  device-enumerator: scanning /sys/bus
  device-enumerator: scanning /sys/class
calling: settle
+ [ -e /conf/param.conf ]
+ maybe_break modules
+ [ n != y ]
+ log_begin_msg Loading essential drivers
+ _log_msg Begin: Loading essential drivers ... 
+ [ n = y ]
+ printf Begin: Loading essential drivers ... 
Begin: Loading essential drivers ... + [ -n  ]
+ load_modules
+ [ -e /conf/modules ]
+ cat /conf/modules
+ read m
+ [ -z md_mod ]
+ printf %.1s md_mod
+ com=m
+ [ m = # ]
+ modprobe md_mod
+ read m
+ [ -z raid1 ]
+ printf %.1s raid1
+ com=r
+ [ r = # ]
+ modprobe raid1
+ read m
+ [ -z dm_mod ]
+ printf %.1s dm_mod
+ com=d
+ [ d = # ]
+ modprobe dm_mod
+ read m
+ [ -z xfs ]
+ printf %.1s xfs
+ com=x
+ [ x = # ]
+ modprobe xfs
+ read m
+ [ -z linear ]
+ printf %.1s linear
+ com=l
+ [ l = # ]
+ modprobe linear
+ read m
+ [ -z multipath ]
+ printf %.1s multipath
+ com=m
+ [ m = # ]
+ modprobe multipath
+ read m
+ [ -z raid0 ]
+ printf %.1s raid0
+ com=r
+ [ r = # ]
+ modprobe raid0
+ read m
+ [ -z raid1 ]
+ printf %.1s raid1
+ com=r
+ [ r = # ]
+ modprobe raid1
+ read m
+ [ -z raid456 ]
+ printf %.1s raid456
+ com=r
+ [ r = # ]
+ modprobe raid456
+ read m
+ [ -z raid5 ]
+ printf %.1s raid5
+ com=r
+ [ r = # ]
+ modprobe raid5
+ read m
+ [ -z raid6 ]
+ printf %.1s raid6
+ com=r
+ [ r = # ]
+ modprobe raid6
+ read m
+ [ -z raid10 ]
+ printf %.1s raid10
+ com=r
+ [ r = # ]
+ modprobe raid10
+ read m
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ [  ]
+ maybe_break premount
+ [ n != y ]
+ log_begin_msg Running /scripts/init-premount
+ _log_msg Begin: Running /scripts/init-premount ... 
+ [ n = y ]
+ printf Begin: Running /scripts/init-premount ... 
Begin: Running /scripts/init-premount ... + run_scripts /scripts/init-premount
+ initdir=/scripts/init-premount
+ [ ! -d /scripts/init-premount ]
+ return
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ maybe_break mount
+ log_begin_msg Mounting root file system
+ _log_msg Begin: Mounting root file system ... 
+ [ n = y ]
+ printf Begin: Mounting root file system ... 
Begin: Mounting root file system ... + . /scripts/local
+ . /scripts/nfs
+ . /scripts/local
+ parse_numeric /dev/mapper/S-root
+ return
+ maybe_break mountroot
+ mount_top
+ local_top
+ [  != yes ]
+ [ n != y ]
+ log_begin_msg Running /scripts/local-top
+ _log_msg Begin: Running /scripts/local-top ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-top ... 
Begin: Running /scripts/local-top ... + run_scripts /scripts/local-top
+ initdir=/scripts/local-top
+ [ ! -d /scripts/local-top ]
+ shift
+ . /scripts/local-top/ORDER
+ /scripts/local-top/cryptopensc
+ [ -e /conf/param.conf ]
+ /scripts/local-top/lvm2
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ /scripts/local-top/cryptroot
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ local_top_used=yes
+ mount_premount
+ local_premount
+ [  != yes ]
+ [ n != y ]
+ log_begin_msg Running /scripts/local-premount
+ _log_msg Begin: Running /scripts/local-premount ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-premount ... 
Begin: Running /scripts/local-premount ... + run_scripts /scripts/local-premount
+ initdir=/scripts/local-premount
+ [ ! -d /scripts/local-premount ]
+ shift
+ . /scripts/local-premount/ORDER
+ /scripts/local-premount/resume
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ local_premount_used=yes
+ mountroot
+ local_mount_root
+ local_top
+ [ yes != yes ]
+ local_top_used=yes
+ local_device_setup /dev/mapper/S-root root
+ local dev_id=/dev/mapper/S-root
+ local name=root
+ local real_dev
+ wait_for_udev 10
+ command -v udevadm
+ udevadm settle --timeout=10
calling: settle
+ [ -n  ]
+ [ /mapper/S-root = /dev/mapper/S-root ]
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ log_begin_msg Waiting for root file system
+ _log_msg Begin: Waiting for root file system ... 
+ [ n = y ]
+ printf Begin: Waiting for root file system ... 
Begin: Waiting for root file system ... + slumber=30
+ [ 0 -gt 30 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=29
+ [ 29 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=28
+ [ 28 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=27
+ [ 27 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=26
+ [ 26 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=25
+ [ 25 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=24
+ [ 24 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=23
+ [ 23 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=22
+ [ 22 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=21
+ [ 21 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=20
+ [ 20 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=19
+ [ 19 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=18
+ [ 18 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=17
+ [ 17 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=16
+ [ 16 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=15
+ [ 15 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=14
+ [ 14 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=13
+ [ 13 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=12
+ [ 12 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=11
+ [ 11 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
mdadm: error opening /dev/md?*: No such file or directory
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=10
+ [ 10 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=9
+ [ 9 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=8
+ [ 8 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=7
+ [ 7 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=6
+ [ 6 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=5
+ [ 5 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=4
+ [ 4 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=3
+ [ 3 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=2
+ [ 2 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=1
+ [ 1 -eq 0 ]
+ true
+ sleep 1
+ local_block /dev/mapper/S-root
+ [ n != y ]
+ log_begin_msg Running /scripts/local-block
+ _log_msg Begin: Running /scripts/local-block ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-block ... 
Begin: Running /scripts/local-block ... + run_scripts /scripts/local-block /dev/mapper/S-root
+ initdir=/scripts/local-block
+ [ ! -d /scripts/local-block ]
+ shift
+ . /scripts/local-block/ORDER
+ /scripts/local-block/cryptroot /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/mdadm /dev/mapper/S-root
+ [ -e /conf/param.conf ]
+ /scripts/local-block/lvm2 /dev/mapper/S-root
  WARNING: Failed to connect to lvmetad. Falling back to device scanning.
  Volume group "S" not found
  Cannot process volume group S
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ slumber=0
+ [ 0 -eq 0 ]
+ log_end_msg 1
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ break
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ real_dev=
+ echo Gave up waiting for root device.  Common problems:
Gave up waiting for root device.  Common problems:
+ echo  - Boot args (cat /proc/cmdline)
 - Boot args (cat /proc/cmdline)
+ echo    - Check rootdelay= (did the system wait long enough?)
   - Check rootdelay= (did the system wait long enough?)
+ [ root = root ]
+ echo    - Check root= (did the system wait for the right device?)
   - Check root= (did the system wait for the right device?)
+ echo  - Missing modules (cat /proc/modules; ls /dev)
 - Missing modules (cat /proc/modules; ls /dev)
+ panic ALERT!  /dev/mapper/S-root does not exist.  Dropping to a shell!
+ local console rest
+ command -v chvt
+ chvt 1
+ echo ALERT!  /dev/mapper/S-root does not exist.  Dropping to a shell!
ALERT!  /dev/mapper/S-root does not exist.  Dropping to a shell!
+ [ -n  ]
+ run_scripts /scripts/panic
+ initdir=/scripts/panic
+ [ ! -d /scripts/panic ]
+ return
+ command -v setsid
+ read console rest
+ [ tty0 = tty0 ]
+ console=tty1
+ REASON=ALERT!  /dev/mapper/S-root does not exist.  Dropping to a shell! PS1=(initramfs)  setsid sh -c exec sh -i <>/dev/tty1 1>&0 2>&1
+ resolve_device /dev/mapper/S-root
+ DEV=/dev/mapper/S-root
+ [ -e /dev/mapper/S-root ]
+ echo /dev/mapper/S-root
+ real_dev=/dev/mapper/S-root
+ get_fstype /dev/mapper/S-root
+ local FS FSTYPE FSSIZE RET
+ FS=/dev/mapper/S-root
+ FSTYPE=unknown
+ fstype /dev/mapper/S-root
+ eval FSTYPE=xfs FSSIZE=8589934592
+ FSTYPE=xfs FSSIZE=8589934592
+ [ xfs = unknown ]
+ RET=0
+ [ -z xfs ]
+ echo xfs
+ return 0
+ DEV=/dev/mapper/S-root
+ ROOT=/dev/mapper/S-root
+ [ -z  ]
+ get_fstype /dev/mapper/S-root
+ local FS FSTYPE FSSIZE RET
+ FS=/dev/mapper/S-root
+ FSTYPE=unknown
+ fstype /dev/mapper/S-root
+ eval FSTYPE=xfs FSSIZE=8589934592
+ FSTYPE=xfs FSSIZE=8589934592
+ [ xfs = unknown ]
+ RET=0
+ [ -z xfs ]
+ echo xfs
+ return 0
+ FSTYPE=xfs
+ local_premount
+ [ yes != yes ]
+ local_premount_used=yes
+ [ y = y ]
+ roflag=-r
+ modprobe xfs
+ checkfs /dev/mapper/S-root root xfs
+ _checkfs_once /dev/mapper/S-root root xfs
+ DEV=/dev/mapper/S-root
+ NAME=root
+ TYPE=xfs
+ [ root = / ]
+ FSCK_LOGFILE=/run/initramfs/fsck.log
+ FSCK_STAMPFILE=/run/initramfs/fsck-root
+ [ xfs = auto ]
+ FSCKCODE=0
+ command -v fsck
+ [ n = y ]
+ [ n = y ]
+ force=
+ [  = y ]
+ [  = n ]
+ fix=-a
+ spinner=
+ [ -z y ]
+ [ n = n ]
+ log_begin_msg Will now check root file system
+ _log_msg Begin: Will now check root file system ... 
+ [ n = y ]
+ printf Begin: Will now check root file system ... 
Begin: Will now check root file system ... + logsave -a -s /run/initramfs/fsck.log fsck -a -V -t xfs /dev/mapper/S-root
fsck from util-linux 2.28
[/sbin/fsck.xfs (1) -- /dev/mapper/S-root] fsck.xfs -a /dev/mapper/S-root 
/sbin/fsck.xfs: XFS file system.
+ FSCKCODE=0
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ [ 0 -eq 32 ]
+ [ 0 -eq 4 ]
+ [ 0 -gt 1 ]
+ 
+ return 0
+ [ xfs != unknown ]
+ mount -r -t xfs /dev/mapper/S-root /root
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ read_fstab_entry /usr
+ found=1
+ [ -f /root/etc/fstab ]
+ read MNT_FSNAME MNT_DIR MNT_TYPE MNT_OPTS MNT_FREQ MNT_PASS MNT_JUNK
+ [ / = /usr ]
+ read MNT_FSNAME MNT_DIR MNT_TYPE MNT_OPTS MNT_FREQ MNT_PASS MNT_JUNK
+ [ none = /usr ]
+ read MNT_FSNAME MNT_DIR MNT_TYPE MNT_OPTS MNT_FREQ MNT_PASS MNT_JUNK
+ [ /tmp = /usr ]
+ read MNT_FSNAME MNT_DIR MNT_TYPE MNT_OPTS MNT_FREQ MNT_PASS MNT_JUNK
+ [ /var = /usr ]
+ read MNT_FSNAME MNT_DIR MNT_TYPE MNT_OPTS MNT_FREQ MNT_PASS MNT_JUNK
+ [ /var/tmp = /usr ]
+ read MNT_FSNAME MNT_DIR MNT_TYPE MNT_OPTS MNT_FREQ MNT_PASS MNT_JUNK
+ [ /media/music = /usr ]
+ read MNT_FSNAME MNT_DIR MNT_TYPE MNT_OPTS MNT_FREQ MNT_PASS MNT_JUNK
+ [ /srv/nfs4/music = /usr ]
+ read MNT_FSNAME MNT_DIR MNT_TYPE MNT_OPTS MNT_FREQ MNT_PASS MNT_JUNK
+ [ /sys/fs/cgroup = /usr ]
+ read MNT_FSNAME MNT_DIR MNT_TYPE MNT_OPTS MNT_FREQ MNT_PASS MNT_JUNK
+ [ /sys/kernel/debug = /usr ]
+ read MNT_FSNAME MNT_DIR MNT_TYPE MNT_OPTS MNT_FREQ MNT_PASS MNT_JUNK
+ [ /sys/kernel/security = /usr ]
+ read MNT_FSNAME MNT_DIR MNT_TYPE MNT_OPTS MNT_FREQ MNT_PASS MNT_JUNK
+ return 1
+ mount_bottom
+ local_bottom
+ [ yes = yes ]
+ [ n != y ]
+ log_begin_msg Running /scripts/local-bottom
+ _log_msg Begin: Running /scripts/local-bottom ... 
+ [ n = y ]
+ printf Begin: Running /scripts/local-bottom ... 
Begin: Running /scripts/local-bottom ... + run_scripts /scripts/local-bottom
+ initdir=/scripts/local-bottom
+ [ ! -d /scripts/local-bottom ]
+ shift
+ . /scripts/local-bottom/ORDER
+ /scripts/local-bottom/cryptopensc
+ [ -e /conf/param.conf ]
+ /scripts/local-bottom/mdadm
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ local_premount_used=no
+ local_top_used=no
+ nfs_bottom
+ [  = yes ]
+ [  = yes ]
+ nfs_premount_used=no
+ nfs_top_used=no
+ local_bottom
+ [ no = yes ]
+ [ no = yes ]
+ local_premount_used=no
+ local_top_used=no
+ maybe_break bottom
+ [ n != y ]
+ log_begin_msg Running /scripts/init-bottom
+ _log_msg Begin: Running /scripts/init-bottom ... 
+ [ n = y ]
+ printf Begin: Running /scripts/init-bottom ... 
Begin: Running /scripts/init-bottom ... + run_scripts /scripts/init-bottom
+ initdir=/scripts/init-bottom
+ [ ! -d /scripts/init-bottom ]
+ shift
+ . /scripts/init-bottom/ORDER
+ /scripts/init-bottom/udev
calling: control
+ [ -e /conf/param.conf ]
+ [ n != y ]
+ log_end_msg
+ _log_msg done.\n
+ [ n = y ]
+ printf done.\n
done.
+ mount -n -o move /run /root/run
+ validate_init /sbin/init
+ run-init -n /root /sbin/init
+ validate_init /sbin/init
+ run-init -n /root /sbin/init
+ maybe_break init
+ unset debug
+ unset MODPROBE_OPTIONS
+ unset DPKG_ARCH
+ unset ROOTFLAGS
+ unset ROOTFSTYPE
+ unset ROOTDELAY
+ unset ROOT
+ unset IP
+ unset BOOT
+ unset BOOTIF
+ unset UBIMTD
+ unset blacklist
+ unset break
+ unset noresume
+ unset panic
+ unset quiet
+ unset readonly
+ unset resume
+ unset resume_offset
+ unset fastboot
+ unset forcefsck
+ unset fsckfix
+ mount -n -o move /sys /root/sys
+ mount -n -o move /proc /root/proc
+ exec run-init /root /sbin/init

More information about the pkg-mdadm-devel mailing list