Aumento acentuado do tempo de inicialização do kernel do Linux; como investigar sobre isso?

1

A partir do kernel 4.5, o tempo de inicialização aumentou de 900ms (4.4.44) para 2.1s (4.9.5).

Eu quero consertar, ou pelo menos ajudar os desenvolvedores, abrindo um relatório de bug apropriado, mas não sei quais informações fornecem para preencher um relatório útil.

Então, como investigar esse problema de tempo de inicialização do kernel?

PS : Eu estou em um Dell XPS 9343 (Broadwell) com Arch Linux x86_64 e Gnome.
PS2 : Na verdade eu tenho o kernel LTS (4.4.44 ) e -ck (4.9.5) instalados no meu sistema e eu os escolho no menu systemd-boot e os tempos acima reportados são retirados do comando systemd-analyze , kernel .
PS3 : À medida que percebo (e meço) o tempo de inicialização aumentado, notei o mesmo no poweroff, mas neste caso não sei como medi-lo.

EDIT1 : saídas solicitadas para o kernel 4.9

[mattia@arch-xps ~]$ systemd-analyze blame
          2.356s dev-sda3.device
          1.648s systemd-fsck@dev-disk-by\x2duuid-88E7\x2d8ACB.service
          1.646s systemd-fsck@dev-disk-by\x2duuid-e2a87acb\x2d9e12\x2d4501\x2dbb
          1.346s tlp.service
          1.324s systemd-journald.service
          1.307s systemd-udev-trigger.service
          1.306s systemd-tmpfiles-setup-dev.service
          1.296s colord.service
          1.005s systemd-journal-flush.service
           947ms [email protected]
           944ms systemd-timesyncd.service
           854ms accounts-daemon.service
           564ms systemd-rfkill.service
           244ms org.cups.cupsd.service
           163ms packagekit.service
            89ms NetworkManager.service
            86ms bluetooth.service
            75ms systemd-logind.service
            53ms upower.service
            53ms [email protected]
            49ms polkit.service
            43ms systemd-udevd.service
            38ms wpa_supplicant.service
            36ms udisks2.service
            28ms systemd-sysctl.service
            25ms systemd-tmpfiles-clean.service
            22ms boot.mount
            20ms systemd-random-seed.service
            19ms gdm.service
            16ms geoclue.service
            16ms systemd-backlight@leds:dell::kbd_backlight.service
            14ms kmod-static-nodes.service
            13ms systemd-remount-fs.service
            11ms dev-sda2.swap
            11ms dev-mqueue.mount
            10ms systemd-tmpfiles-setup.service
            10ms sys-kernel-debug.mount
             8ms systemd-backlight@backlight:intel_backlight.service
             7ms home.mount
             6ms systemd-update-utmp.service
             6ms sys-kernel-config.mount
             6ms systemd-user-sessions.service
             5ms dev-hugepages.mount
             5ms rtkit-daemon.service
             3ms sys-fs-fuse-connections.mount
             3ms tmp.mount

[mattia@arch-xps ~]$ systemd-analyze critical-chain 
The time after the unit is active or started is printed after the "@" character.
The time the unit takes to start is printed after the "+" character.

graphical.target @5.282s
└─gdm.service @5.258s +19ms
  └─systemd-user-sessions.service @5.244s +6ms
    └─nss-user-lookup.target @5.288s

EDIT2 : saída solicitada para o kernel 4.4

[mattia@arch-xps ~]$ systemd-analyze blame
          1.356s systemd-fsck@dev-disk-by\x2duuid-e2a87acb\x2d9e12\x2d4501\x2dbb
          1.355s systemd-rfkill.service
           895ms [email protected]
           861ms tlp.service
           474ms dev-sda3.device
           379ms systemd-localed.service
           310ms systemd-hostnamed.service
           310ms systemd-timedated.service
           255ms systemd-timesyncd.service
           188ms org.cups.cupsd.service
           146ms systemd-journald.service
           112ms packagekit.service
            60ms bluetooth.service
            60ms NetworkManager.service
            59ms accounts-daemon.service
            58ms upower.service
            49ms systemd-udevd.service
            48ms systemd-fsck@dev-disk-by\x2duuid-88E7\x2d8ACB.service
            37ms [email protected]
            36ms polkit.service
            33ms systemd-udev-trigger.service
            25ms systemd-journal-flush.service
            24ms systemd-tmpfiles-setup-dev.service
            22ms udisks2.service
            17ms gdm.service
            16ms kmod-static-nodes.service
            16ms dev-sda2.swap
            16ms boot.mount
            15ms colord.service
            15ms sys-kernel-debug.mount
            13ms systemd-remount-fs.service
            13ms dev-hugepages.mount
            13ms geoclue.service
            13ms systemd-sysctl.service
            13ms home.mount
            10ms systemd-logind.service
             9ms sys-kernel-config.mount
             8ms wpa_supplicant.service
             8ms systemd-tmpfiles-setup.service
             7ms systemd-random-seed.service
             7ms systemd-backlight@leds:dell::kbd_backlight.service
             5ms systemd-update-utmp.service
             5ms rtkit-daemon.service
             4ms systemd-user-sessions.service
             4ms tmp.mount
             3ms systemd-backlight@backlight:intel_backlight.service
             3ms dev-mqueue.mount
             2ms sys-fs-fuse-connections.mount

[mattia@arch-xps ~]$ systemd-analyze critical-chain 
The time after the unit is active or started is printed after the "@" character.
The time the unit takes to start is printed after the "+" character.

graphical.target @2.555s
└─gdm.service @2.536s +17ms
  └─systemd-user-sessions.service @2.525s +4ms
    └─nss-user-lookup.target @2.559s

EDIT3 : não, eles têm aproximadamente o mesmo tamanho

[mattia@arch-xps ~]$ ls /boot/
total 62M
drwxr-xr-x  5 root root 4.0K Jan  1  1970 .
drwxr-xr-x 17 root root 4.0K Dec 10 17:31 ..
drwxr-xr-x  6 root root 4.0K Dec 14 17:49 EFI
drwxr-xr-x  2 root root 4.0K Jan 11 16:39 grub
drwxr-xr-x  3 root root 4.0K May 14  2016 loader
-rwxr-xr-x  1 root root  21M Jan 20 15:40 initramfs-linux-ck-fallback.img
-rwxr-xr-x  1 root root 5.7M Jan 20 15:40 initramfs-linux-ck.img
-rwxr-xr-x  1 root root  20M Jan 17 18:01 initramfs-linux-lts-fallback.img
-rwxr-xr-x  1 root root 5.6M Jan 17 18:01 initramfs-linux-lts.img
-rwxr-xr-x  1 root root 961K Nov 15 18:43 intel-ucode.img
-rwxr-xr-x  1 root root 4.6M Jan 20 15:38 vmlinuz-linux-ck
-rwxr-xr-x  1 root root 4.3M Jan 15 15:05 vmlinuz-linux-lts

EDIT4 : uma tentativa com o kernel do repositório oficial (ver sexto sétimo comentários), dá um tempo de inicialização significativamente diferente de 1.3s, mas ainda maior que o kernel 4.4

[mattia@arch-xps ~]$ systemd-analyze blame
          1.979s dev-sda3.device
          1.369s systemd-journald.service
          1.347s colord.service
           871ms systemd-udev-trigger.service
           868ms [email protected]
           868ms systemd-tmpfiles-setup-dev.service
           793ms systemd-udevd.service
           782ms tlp.service
           508ms systemd-timesyncd.service
           503ms [email protected]
           473ms wpa_supplicant.service
           177ms org.cups.cupsd.service
           110ms packagekit.service
            85ms NetworkManager.service
            85ms systemd-journal-flush.service
            66ms systemd-fsck@dev-disk-by\x2duuid-e2a87acb\x2d9e12\x2d4501\x2dbb
            49ms upower.service
            47ms systemd-fsck@dev-disk-by\x2duuid-88E7\x2d8ACB.service
            44ms accounts-daemon.service
            28ms bluetooth.service
            27ms polkit.service
            24ms gdm.service
            22ms udisks2.service
            18ms systemd-rfkill.service
            16ms systemd-logind.service
            15ms geoclue.service
            13ms boot.mount
            13ms dev-hugepages.mount
            12ms dev-mqueue.mount
            11ms systemd-remount-fs.service
            11ms systemd-backlight@leds:dell::kbd_backlight.service
            11ms systemd-sysctl.service
            10ms sys-kernel-config.mount
             9ms dev-sda2.swap
             9ms kmod-static-nodes.service
             8ms systemd-user-sessions.service
             7ms home.mount
             7ms systemd-backlight@backlight:intel_backlight.service
             7ms systemd-tmpfiles-setup.service
             6ms systemd-update-utmp.service
             5ms tmp.mount
             5ms systemd-random-seed.service
             4ms rtkit-daemon.service
             4ms sys-fs-fuse-connections.mount
             2ms sys-kernel-debug.mount
[mattia@arch-xps ~]$ systemd-analyze critical-chain 
The time after the unit is active or started is printed after the "@" character.
The time the unit takes to start is printed after the "+" character.

graphical.target @4.604s
└─gdm.service @4.397s +24ms
  └─systemd-user-sessions.service @4.379s +8ms
    └─nss-user-lookup.target @4.425s
    
por mattia.b89 20.01.2017 / 12:54

0 respostas