Расхождение во времени между dmesg и journalctl -k

Редактировать: я не мог выяснить, почему происходит этот странный эффект, но я обнаружил, что виновник реальной проблемы (медленная загрузка) был таким же, как описано здесь: https://bugs.launchpad.net/ubuntu/+source/ubiquity/+bug/1763611

Я все еще оставлю это открытым, поскольку вопрос остается.


Я пытаюсь проанализировать время запуска друга, чей ноутбук "внезапно" стал медленнее.

dmesg показывает мне это:

[    2.348642] usb 1-8: New USB device found, idVendor=0bda, idProduct=0129
[    2.348644] usb 1-8: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    2.348645] usb 1-8: Product: USB2.0-CRW
[    2.348646] usb 1-8: Manufacturer: Generic
[    2.348648] usb 1-8: SerialNumber: 20100201396000000
[    2.352517] usbcore: registered new interface driver rtsx_usb
[    2.844331] [drm] RC6 on
[   34.760930] EXT4-fs (dm-0): mounted filesystem with ordered data mode. Opts: (null)
[   34.933397] ip_tables: (C) 2000-2006 Netfilter Core Team
[   35.006893] systemd[1]: systemd 237 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid)

который также подтверждает ручные измерения и вывод systemd-analyze Говоря, что ноутбук тратит примерно 35 секунд в ядре на загрузку, оставшиеся ~12 секунд у пользователя происходят из-за устройства, ожидающего подключения NetworkManager, но здесь это не имеет значения. В любом случае, с SSD ноутбук не должен тратить так много времени на загрузку, особенно не для монтирования файловой системы.

Я тоже побежал journalctl -k который должен дать тот же результат, но я получаю:

Aug 30 21:13:45 laptop kernel: usb 1-8: New USB device found, idVendor=0bda, idProduct=0129
Aug 30 21:13:45 laptop kernel: usb 1-8: New USB device strings: Mfr=1, Product=2, SerialNumber=3
Aug 30 21:13:45 laptop kernel: usb 1-8: Product: USB2.0-CRW
Aug 30 21:13:45 laptop kernel: usb 1-8: Manufacturer: Generic
Aug 30 21:13:45 laptop kernel: usb 1-8: SerialNumber: 20100201396000000
Aug 30 21:13:45 laptop kernel: usbcore: registered new interface driver rtsx_usb
Aug 30 21:13:45 laptop kernel: [drm] RC6 on
Aug 30 21:13:45 laptop kernel: EXT4-fs (dm-0): mounted filesystem with ordered data mode. Opts: (null)
Aug 30 21:13:45 laptop kernel: ip_tables: (C) 2000-2006 Netfilter Core Team
Aug 30 21:13:45 laptop systemd[1]: systemd 237 running in system mode. (+PAM +AUDIT +SELINUX +IMA +APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD -IDN2 +IDN -PCRE2 default-hierarchy=hybrid)

... так что нет такого временного промежутка от journalctlс точки зрения.

Я понятия не имею, откуда эта разница во времени.

1 ответ

Чтобы получить тот же формат отметки времени, что и для dmesg с journalctl, попробуйте

journalctl -o short-monotonic -b

Пример вывода с journalctl

...
[    1.003458] marvin kernel: usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    1.003460] marvin kernel: usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.003461] marvin kernel: usb usb1: Product: xHCI Host Controller
[    1.003462] marvin kernel: usb usb1: Manufacturer: Linux 4.15.0-33-generic xhci-hcd
[    1.003463] marvin kernel: usb usb1: SerialNumber: 0000:00:14.0
[    1.003596] marvin kernel: hub 1-0:1.0: USB hub found
[    1.003611] marvin kernel: hub 1-0:1.0: 16 ports detected
[    1.004505] marvin kernel: xhci_hcd 0000:00:14.0: xHCI Host Controller
[    1.004507] marvin kernel: xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 2
[    1.004510] marvin kernel: xhci_hcd 0000:00:14.0: Host supports USB 3.0  SuperSpeed
[    1.004533] marvin kernel: usb usb2: New USB device found, idVendor=1d6b, idProduct=0003
[    1.004535] marvin kernel: usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.004536] marvin kernel: usb usb2: Product: xHCI Host Controller
[    1.004537] marvin kernel: usb usb2: Manufacturer: Linux 4.15.0-33-generic xhci-hcd
[    1.004538] marvin kernel: usb usb2: SerialNumber: 0000:00:14.0
...

По сравнению с тем же выходом из dmesg

...
[    1.003458] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[    1.003460] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.003461] usb usb1: Product: xHCI Host Controller
[    1.003462] usb usb1: Manufacturer: Linux 4.15.0-33-generic xhci-hcd
[    1.003463] usb usb1: SerialNumber: 0000:00:14.0
[    1.003596] hub 1-0:1.0: USB hub found
[    1.003611] hub 1-0:1.0: 16 ports detected
[    1.004505] xhci_hcd 0000:00:14.0: xHCI Host Controller
[    1.004507] xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 2
[    1.004510] xhci_hcd 0000:00:14.0: Host supports USB 3.0  SuperSpeed
[    1.004533] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003
[    1.004535] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.004536] usb usb2: Product: xHCI Host Controller
[    1.004537] usb usb2: Manufacturer: Linux 4.15.0-33-generic xhci-hcd
[    1.004538] usb usb2: SerialNumber: 0000:00:14.0
...
Другие вопросы по тегам