Bug#917247: udev: Many modules are no longer automatically loaded at boot

gregor herrmann gregoa at debian.org
Tue Jan 1 23:58:52 GMT 2019


On Tue, 01 Jan 2019 16:52:31 +0100, Michael Biebl wrote:

> Am 01.01.19 um 01:40 schrieb gregor herrmann:
> > [0] The initialization problems, with loads of:
> > WARNING: Device /dev/xxx not initialized in udev database even after waiting 10000000 microseconds.
> 
> Is that the only error message from udev?
> Have you tried running udev with udev debug logging enabled (see
> /etc/udev/udev.conf).
> Maybe a debug log provides more hints what's going wrong.

Thanks for your quick reply and your debugging hints.

This afternoon I tried to reproduce the issues in a VM but
interestingly both didn't show up there (neither the missing modules
nor the lvm troubles). -- No idea which difference between the VM and
my real system is responsible here.


So I tried on my laptop now.

This is amd64 with the current 4.19 kernel from unstable, sysvinit,
luks+lvm on the internal SSD and an external (USB) disk.


Yesterday I downgraded udev to 239-15 and lvm2 to 2.02.176-4.1 to get
back a working system.


Now I first saved the ouptput of lsmod (attachment
lsmod.udev-239-15), updated udev to 240-2, turned on debugging
(udev_log=debug; hope that's correct?), and rebooted.


What I then found again was a massive amount of not loaded modules.
For comparison cf. attachment lsmod.udev-240-2.

No visible lvm troubles at that point.


Then I updated lvm2 to 240-2, and already at the installation I got:

#v+
Installing new version of config file /etc/lvm/lvm.conf ...

[....] Setting up LVM Volume Groups...  WARNING: Device /dev/sda not initialized in udev database even after waiting 10000000 microseconds.
  WARNING: Device /dev/dm-0 not initialized in udev database even after waiting
10000000 microseconds.
  WARNING: Device /dev/sda1 not initialized in udev database even after waiting
10000000 microseconds.
[etc.]
#v-


After a reboot I got the exakt same WARNINGS at "Setting up LVM
Volume Groups..." during startup, and also on everything related to
lvm, like plain `lvs' etc.


Lines related to udev in the syslog are rare:

with old lvm2:

Jan  1 23:29:56 jadzia kernel: <1055>[   18.202686] systemd-udevd[548]: Set children_max to 40
Jan  1 23:29:56 jadzia kernel: <1055>[   18.202749] systemd-udevd[548]: Dedicated cgroup not found: No medium found
Jan  1 23:30:02 jadzia kernel: <1055>[   32.006943] systemd-udevd[548]: Validate module index
Jan  1 23:30:02 jadzia kernel: <1055>[   32.006954] systemd-udevd[548]: Check if link configuration needs reloading.
Jan  1 23:30:08 jadzia kernel: <1055>[   38.494397] systemd-udevd[548]: Cleanup idle workers
Jan  1 23:30:08 jadzia kernel: <1055>[   38.494798] systemd-udevd[548]: Worker [4333] exited
Jan  1 23:37:05 jadzia kernel: <1055>[  455.708050] systemd-udevd[548]: Validate module index
Jan  1 23:37:05 jadzia kernel: <1055>[  455.708083] systemd-udevd[548]: Check if link configuration needs reloading.

with new lvm2:

Jan  1 23:53:40 jadzia kernel: <1055>[   18.268102] systemd-udevd[534]: Set children_max to 40
Jan  1 23:53:40 jadzia kernel: <1055>[   18.268162] systemd-udevd[534]: Dedicated cgroup not found: No medium found
Jan  1 23:53:40 jadzia kernel: <1055>[   24.055069] systemd-udevd[534]: Cleanup idle workers
Jan  1 23:53:40 jadzia kernel: <1055>[  181.898002] systemd-udevd[534]: Validate module index
Jan  1 23:53:40 jadzia kernel: <1055>[  181.898028] systemd-udevd[534]: Check if link configuration needs reloading.
Jan  1 23:53:42 jadzia kernel: <1055>[  189.054934] systemd-udevd[534]: Cleanup idle workers
Jan  1 23:57:06 jadzia kernel: <1055>[  392.914786] systemd-udevd[534]: Unload module index
Jan  1 23:57:06 jadzia kernel: <1055>[  392.914897] systemd-udevd[534]: Unloaded link configuration context.
Jan  1 23:57:06 jadzia kernel: [  392.915694] systemd-udevd: 2084 output lines suppressed due to ratelimiting
Jan  1 23:57:06 jadzia kernel: <1055>[  392.962294] systemd-udevd[7278]: Set children_max to 40
Jan  1 23:57:06 jadzia kernel: <1055>[  392.962371] systemd-udevd[7278]: Dedicated cgroup not found: No medium found
Jan  1 23:57:29 jadzia kernel: <1055>[  416.241338] systemd-udevd[7278]: Validate module index
Jan  1 23:57:29 jadzia kernel: <1055>[  416.241350] systemd-udevd[7278]: Check if link configuration needs reloading.
Jan  1 23:58:08 jadzia kernel: <1055>[  455.156872] systemd-udevd[7278]: Validate module index
Jan  1 23:58:08 jadzia kernel: <1055>[  455.156901] systemd-udevd[7278]: Check if link configuration needs reloading.


/var/log/dmesg* has not much more:

with old lvm2:

[   18.268085] systemd-udevd[534]: Unknown filesystem type 62656572 mounted on /sys/fs/cgroup.
[   18.268097] systemd-udevd[534]: Failed to determine root cgroup, ignoring cgroup memory limit: No medium found
[   18.268102] systemd-udevd[534]: Set children_max to 40
[   18.268122] systemd-udevd[534]: Failed to symlink /proc/kcore to /dev/core: File exists
[   18.268129] systemd-udevd[534]: Failed to symlink /proc/self/fd to /dev/fd: File exists
[   18.268136] systemd-udevd[534]: Failed to symlink /proc/self/fd/0 to /dev/stdin: File exists
[   18.268143] systemd-udevd[534]: Failed to symlink /proc/self/fd/1 to /dev/stdout: File exists
[   18.268149] systemd-udevd[534]: Failed to symlink /proc/self/fd/2 to /dev/stderr: File exists
[   18.268158] systemd-udevd[534]: Unknown filesystem type 62656572 mounted on /sys/fs/cgroup.
[   18.268162] systemd-udevd[534]: Dedicated cgroup not found: No medium found
[   24.055069] systemd-udevd[534]: Cleanup idle workers
[  181.897926] systemd-udevd[534]: 254:5: Device (SEQNUM=2071, ACTION=add) is queued
[  181.898002] systemd-udevd[534]: Validate module index
[  181.898028] systemd-udevd[534]: Check if link configuration needs reloading.
[  181.898301] systemd-udevd[534]: Successfully forked off '(worker)' as PID 1176.
[  181.898406] systemd-udevd[534]: 254:5: Worker [1176] is forked for processing SEQNUM=2071.
[  181.898705] systemd-udevd[534]: dm-5: Device (SEQNUM=2072, ACTION=add) is queued
[  181.898909] systemd-udevd[534]: Successfully forked off '(worker)' as PID 1178.
[  181.899025] systemd-udevd[534]: dm-5: Worker [1178] is forked for processing SEQNUM=2072.
[  181.899338] systemd-udevd[534]: dm-5: Device (SEQNUM=2073, ACTION=change) is queued


with new lvm2:

[   18.202665] systemd-udevd[548]: Unknown filesystem type 62656572 mounted on /sys/fs/cgroup.
[   18.202681] systemd-udevd[548]: Failed to determine root cgroup, ignoring cgroup memory limit: No medium found
[   18.202686] systemd-udevd[548]: Set children_max to 40
[   18.202706] systemd-udevd[548]: Failed to symlink /proc/kcore to /dev/core: File exists
[   18.202714] systemd-udevd[548]: Failed to symlink /proc/self/fd to /dev/fd: File exists
[   18.202721] systemd-udevd[548]: Failed to symlink /proc/self/fd/0 to /dev/stdin: File exists
[   18.202729] systemd-udevd[548]: Failed to symlink /proc/self/fd/1 to /dev/stdout: File exists
[   18.202736] systemd-udevd[548]: Failed to symlink /proc/self/fd/2 to /dev/stderr: File exists
[   18.202744] systemd-udevd[548]: Unknown filesystem type 62656572 mounted on /sys/fs/cgroup.
[   18.202749] systemd-udevd[548]: Dedicated cgroup not found: No medium found
[   23.607394] systemd-udevd[548]: sunrpc: Device (SEQNUM=2084, ACTION=add) is queued
[   23.607416] systemd-udevd[548]: sunrpc: sd-device-monitor: Passed 129 byte to netlink monitor
[   23.607514] systemd-udevd[548]: rpc_inode_cache: Device (SEQNUM=2085, ACTION=add) is queued
[   23.607599] systemd-udevd[548]: rpc_inode_cache: sd-device-monitor: Passed 141 byte to netlink monitor



Maybe there's something anyone can make sense of … Sorry for not
being able to provide something clearer right now.


Cheers,
gregor

-- 
 .''`.  https://info.comodo.priv.at -- Debian Developer https://www.debian.org
 : :' : OpenPGP fingerprint D1E1 316E 93A7 60A8 104D  85FA BB3A 6801 8649 AA06
 `. `'  Member VIBE!AT & SPI Inc. -- Supporter Free Software Foundation Europe
   `-   NP: Bob Dylan: Like A Rolling Stone
-------------- next part --------------
Module                  Size  Used by
nls_ascii              16384  0
nls_cp437              20480  0
vfat                   20480  0
fat                    86016  1 vfat
fuse                  122880  2
vboxpci                28672  0
vboxnetadp             28672  0
vboxnetflt             32768  0
vboxdrv               483328  3 vboxpci,vboxnetadp,vboxnetflt
zram                   28672  4
zsmalloc               28672  1 zram
rpcsec_gss_krb5        45056  0
pci_stub               16384  1
nfsv4                 684032  2
dns_resolver           16384  1 nfsv4
cpufreq_userspace      16384  0
cpufreq_powersave      16384  0
cpufreq_conservative    16384  0
binfmt_misc            20480  1
uinput                 20480  1
nfsd                  421888  2
auth_rpcgss            73728  2 nfsd,rpcsec_gss_krb5
nfs_acl                16384  1 nfsd
nfs                   311296  3 nfsv4
lockd                 118784  2 nfsd,nfs
grace                  16384  2 nfsd,lockd
fscache               385024  2 nfsv4,nfs
sunrpc                430080  14 nfsd,nfsv4,auth_rpcgss,lockd,rpcsec_gss_krb5,nfs_acl,nfs
acpi_call              16384  0
ip6_tables             32768  0
ip_tables              28672  0
x_tables               45056  2 ip6_tables,ip_tables
joydev                 24576  0
hdaps                  16384  1
tp_smapi               32768  0
thinkpad_ec            16384  2 hdaps,tp_smapi
loop                   32768  0
parport_pc             32768  0
ppdev                  20480  0
lp                     20480  0
parport                57344  3 parport_pc,lp,ppdev
btusb                  53248  0
btrtl                  16384  1 btusb
btbcm                  16384  1 btusb
btintel                24576  1 btusb
uvcvideo              118784  0
bluetooth             643072  4 btrtl,btintel,btbcm,btusb
videobuf2_vmalloc      16384  1 uvcvideo
cdc_mbim               16384  0
videobuf2_memops       16384  1 videobuf2_vmalloc
videobuf2_v4l2         28672  1 uvcvideo
videobuf2_common       53248  2 videobuf2_v4l2,uvcvideo
drbg                   28672  1
videodev              212992  3 videobuf2_v4l2,uvcvideo,videobuf2_common
ansi_cprng             16384  0
ecdh_generic           24576  1 bluetooth
media                  45056  2 videodev,uvcvideo
cdc_ncm                40960  1 cdc_mbim
usbnet                 49152  2 cdc_mbim,cdc_ncm
cdc_acm                32768  0
cdc_wdm                24576  1 cdc_mbim
mii                    16384  1 usbnet
snd_hda_codec_hdmi     57344  1
snd_hda_codec_conexant    24576  1
snd_hda_codec_generic    86016  1 snd_hda_codec_conexant
arc4                   16384  0
intel_rapl             24576  0
x86_pkg_temp_thermal    16384  0
intel_powerclamp       16384  0
coretemp               16384  0
kvm_intel             241664  0
i915                 1728512  11
wmi_bmof               16384  0
kvm                   729088  1 kvm_intel
snd_hda_intel          45056  5
snd_hda_codec         151552  4 snd_hda_codec_generic,snd_hda_codec_conexant,snd_hda_codec_hdmi,snd_hda_intel
irqbypass              16384  1 kvm
snd_hda_core           94208  5 snd_hda_codec_generic,snd_hda_codec_conexant,snd_hda_codec_hdmi,snd_hda_intel,snd_hda_codec
snd_hwdep              16384  1 snd_hda_codec
intel_cstate           16384  0
snd_pcm_oss            61440  0
snd_mixer_oss          28672  1 snd_pcm_oss
intel_uncore          135168  0
drm_kms_helper        204800  1 i915
evdev                  28672  30
intel_rapl_perf        16384  0
snd_pcm               114688  6 snd_hda_codec_hdmi,snd_hda_intel,snd_hda_codec,snd_pcm_oss,snd_hda_core
drm                   483328  12 drm_kms_helper,i915
serio_raw              16384  0
thinkpad_acpi         106496  1
pcspkr                 16384  0
snd_timer              36864  2 snd_pcm
tpm_tis                16384  0
nvram                  16384  1 thinkpad_acpi
iTCO_wdt               16384  0
sg                     36864  0
i2c_algo_bit           16384  1 i915
tpm_tis_core           20480  1 tpm_tis
snd                    94208  19 snd_hda_codec_generic,snd_hda_codec_conexant,snd_hda_codec_hdmi,snd_hwdep,snd_hda_intel,snd_hda_codec,snd_timer,snd_pcm_oss,thinkpad_acpi,snd_pcm,snd_mixer_oss
iTCO_vendor_support    16384  1 iTCO_wdt
mei_me                 45056  0
wmi                    28672  1 wmi_bmof
tpm                    65536  2 tpm_tis,tpm_tis_core
rfkill                 28672  2 bluetooth,thinkpad_acpi
mei                   118784  1 mei_me
soundcore              16384  1 snd
rng_core               16384  1 tpm
ac                     16384  0
video                  45056  2 thinkpad_acpi,i915
battery                24576  1 thinkpad_acpi
button                 16384  0
pcc_cpufreq            16384  0
ext4                  733184  5
crc16                  16384  2 bluetooth,ext4
mbcache                16384  1 ext4
jbd2                  122880  1 ext4
fscrypto               32768  1 ext4
algif_skcipher         16384  0
af_alg                 28672  1 algif_skcipher
dm_crypt               40960  2
dm_mod                151552  27 dm_crypt
hid_generic            16384  0
usbhid                 57344  0
hid                   139264  2 usbhid,hid_generic
sr_mod                 28672  0
cdrom                  69632  1 sr_mod
sd_mod                 53248  5
uas                    28672  0
ata_generic            16384  0
usb_storage            69632  2 uas
crct10dif_pclmul       16384  0
crc32_pclmul           16384  0
crc32c_intel           24576  5
ghash_clmulni_intel    16384  0
pcbc                   16384  0
aesni_intel           200704  7
ata_piix               36864  2
aes_x86_64             20480  1 aesni_intel
crypto_simd            16384  1 aesni_intel
cryptd                 28672  5 crypto_simd,ghash_clmulni_intel,aesni_intel
xhci_pci               16384  0
glue_helper            16384  1 aesni_intel
libata                278528  2 ata_piix,ata_generic
xhci_hcd              266240  1 xhci_pci
sdhci_pci              45056  0
cqhci                  28672  1 sdhci_pci
sdhci                  57344  1 sdhci_pci
scsi_mod              249856  6 sd_mod,usb_storage,uas,libata,sg,sr_mod
psmouse               172032  0
ehci_pci               16384  0
ehci_hcd               94208  1 ehci_pci
mmc_core              172032  3 sdhci,cqhci,sdhci_pci
i2c_i801               28672  0
lpc_ich                28672  0
usbcore               290816  14 xhci_hcd,ehci_pci,usbnet,usbhid,cdc_mbim,cdc_acm,cdc_ncm,usb_storage,cdc_wdm,uvcvideo,ehci_hcd,btusb,xhci_pci,uas
e1000e                282624  0
usb_common             16384  1 usbcore
thermal                20480  0
-------------- next part --------------
Module                  Size  Used by
zram                   28672  4
zsmalloc               28672  1 zram
pci_stub               16384  1
vboxpci                28672  0
rpcsec_gss_krb5        45056  0
vboxnetadp             28672  0
nfsv4                 684032  2
vboxnetflt             32768  0
vboxdrv               483328  3 vboxpci,vboxnetadp,vboxnetflt
dns_resolver           16384  1 nfsv4
cpufreq_userspace      16384  0
cpufreq_powersave      16384  0
cpufreq_conservative    16384  0
binfmt_misc            20480  1
uinput                 20480  1
nfsd                  421888  2
auth_rpcgss            73728  2 nfsd,rpcsec_gss_krb5
nfs_acl                16384  1 nfsd
nfs                   311296  3 nfsv4
lockd                 118784  2 nfsd,nfs
grace                  16384  2 nfsd,lockd
fscache               385024  2 nfsv4,nfs
sunrpc                430080  14 nfsd,nfsv4,auth_rpcgss,lockd,rpcsec_gss_krb5,nfs_acl,nfs
acpi_call              16384  0
ip6_tables             32768  0
ip_tables              28672  0
x_tables               45056  2 ip6_tables,ip_tables
joydev                 24576  0
hdaps                  16384  1
tp_smapi               32768  0
thinkpad_ec            16384  2 hdaps,tp_smapi
evdev                  28672  6
pcspkr                 16384  0
nvram                  16384  0
loop                   32768  0
parport_pc             32768  0
ppdev                  20480  0
lp                     20480  0
parport                57344  3 parport_pc,lp,ppdev
ext4                  733184  5
crc16                  16384  1 ext4
mbcache                16384  1 ext4
jbd2                  122880  1 ext4
fscrypto               32768  1 ext4
algif_skcipher         16384  0
af_alg                 28672  1 algif_skcipher
dm_crypt               40960  2
dm_mod                151552  27 dm_crypt
hid_generic            16384  0
usbhid                 57344  0
hid                   139264  2 usbhid,hid_generic
sr_mod                 28672  0
cdrom                  69632  1 sr_mod
sd_mod                 53248  5
uas                    28672  0
ata_generic            16384  0
usb_storage            69632  2 uas
crct10dif_pclmul       16384  0
crc32_pclmul           16384  0
crc32c_intel           24576  5
ghash_clmulni_intel    16384  0
pcbc                   16384  0
xhci_pci               16384  0
xhci_hcd              266240  1 xhci_pci
ata_piix               36864  2
libata                278528  2 ata_piix,ata_generic
aesni_intel           200704  7
scsi_mod              249856  5 sd_mod,usb_storage,uas,libata,sr_mod
aes_x86_64             20480  1 aesni_intel
crypto_simd            16384  1 aesni_intel
cryptd                 28672  5 crypto_simd,ghash_clmulni_intel,aesni_intel
glue_helper            16384  1 aesni_intel
sdhci_pci              45056  0
ehci_pci               16384  0
cqhci                  28672  1 sdhci_pci
ehci_hcd               94208  1 ehci_pci
psmouse               172032  0
sdhci                  57344  1 sdhci_pci
lpc_ich                28672  0
i2c_i801               28672  0
mmc_core              172032  3 sdhci,cqhci,sdhci_pci
usbcore               290816  7 xhci_hcd,ehci_pci,usbhid,usb_storage,ehci_hcd,xhci_pci,uas
e1000e                282624  0
usb_common             16384  1 usbcore
thermal                20480  0
-------------- next part --------------
A non-text attachment was scrubbed...
Name: signature.asc
Type: application/pgp-signature
Size: 963 bytes
Desc: Digital Signature
URL: <http://alioth-lists.debian.net/pipermail/pkg-systemd-maintainers/attachments/20190102/f1ef2fe3/attachment.sig>


More information about the Pkg-systemd-maintainers mailing list