[Démarrage] Latence triggering uevents (résolut)

[pepit]

Sur mon deuxième pc D620 Archlinux j'ai pas le temps de lire! Mais là il lui faut plus de 10 secondes pour passer l'étape!

[papajoke]

Bonjour,

dmesg -T pour lire au calme et trouver le problème

[pepit]

Bonjour, je ne vois rien ce rapportant à mon problème, mais ce qui s'affiche est difficile à digérer pour moi!
Très peux d'erreurs sont signalées et il semble qu'elles aparaissent plus tard dans la séquence de démarrage. Comment reconnaitre le problème?

[pepit]

Bonsoir, voilà que je viens de remplacer mon DD SAS par un SSD M2 850. Dommage d'avoir un disque si rapide sur une telle machine pour attendre plus de 50s à chaque démarrage alors que mon vieu D630 le fait en moins de 10s!
Je pensait qu'avec ce nouveau disque je gommerai le problème mais non: j'ai 4 latences en tout chaque démarage:
1 : (non Arch) le bios du controleur SAS Dell: 20s
2 : Chargement du noyau linux : 5s
3 : Triggering uevents : 15s
4 : la dernière séquence du lancement de KDE Plasma 5.5.1: 10s (on dirais que c'est volontaire pour faire un effet de fondu)

La sortie de dmesg -T est trop grosse pour être postée, mais je ne vois aucunes erreurs flagrante.

Des idées?

[waitnsea]

Bonsoir,
Essaie systemd-analyze blame ?

[pepit]

Oups là, je crois avoir trouvé ca dans la sortie "dmesg -T":

Code : Tout sélectionner

[ven. déc. 18 17:48:57 2015] hid-generic 0003:0463:FFFF.0006: hiddev0,hidraw5: USB HID v1.10 Device [EATON Ellipse MAX] on usb-0000:00:14.0-12/input0
[ven. déc. 18 17:49:13 2015] scsi host12: ioc0: LSISAS1068E B3, FwRev=00192f00h, Ports=1, MaxQ=266, IRQ=16

J'ai aussi cette erreur sans conséquence de durée mais dont j'ignore la cause:

Code : Tout sélectionner

[ven. déc. 18 17:49:15 2015] pmd_set_huge: Cannot satisfy [mem 0xf8000000-0xf8200000] with a huge-page mapping due to MTRR override.
[ven. déc. 18 17:49:16 2015] e1000e: eno1 NIC Link is Up 100 Mbps Full Duplex, Flow Control: None

Et aussi ceci:

Code : Tout sélectionner

[ven. déc. 18 17:49:16 2015] IPv6: ADDRCONF(NETDEV_CHANGE): eno1: link becomes ready
[ven. déc. 18 17:50:03 2015] EXT4-fs (sdd2): mounting ext2 file system using the ext4 subsystem

J'essayerai de redémarrer en débranchant l'usb de l'onduleur pour voir, je vous tiendrai au courant de la suite!

[pepit]

Bonsoir,
Essaie systemd-analyze blame ?

Extra cette commande!:

Code : Tout sélectionner

	   1.130s xdm.service
           661ms man-db.service
           290ms dev-sdc3.device
           211ms network.service
           193ms mnt-WIN.mount
           159ms mnt-WINDOCS.mount
           148ms systemd-fsck@dev-disk-by\x2duuid-58d1a8ed\x2dbcdb\x2d4c9c\x2d8c48\x2df1d50fa43a7f.service
            99ms systemd-journald.service
            82ms systemd-udev-trigger.service
            59ms udisks2.service
            40ms user@1000.service
            40ms colord.service
            34ms alsa-restore.service
            30ms systemd-vconsole-setup.service
            27ms systemd-udevd.service
            27ms mnt-MAISON.mount
            23ms polkit.service
            19ms systemd-journal-flush.service
            16ms shadow.service
            14ms systemd-tmpfiles-setup-dev.service
            14ms kmod-static-nodes.service
            14ms systemd-remount-fs.service
            11ms ntpd.service
            11ms logrotate.service
            11ms sys-kernel-debug.mount
            10ms tmp.mount
             9ms avahi-daemon.service
             9ms systemd-logind.service
             9ms dev-mqueue.mount
             8ms systemd-sysctl.service
             7ms dev-disk-by\x2duuid-1dcdf1d0\x2d436b\x2d4a1b\x2d9f6a\x2d62cfecf04da6.swap
             7ms dev-hugepages.mount
             7ms systemd-tmpfiles-clean.service
             7ms systemd-tmpfiles-setup.service
             6ms upower.service
             5ms systemd-random-seed.service
             4ms systemd-update-utmp.service
             3ms systemd-user-sessions.service
             3ms sys-kernel-config.mount
             2ms rtkit-daemon.service
             2ms sys-fs-fuse-connections.mount

[waitnsea]

... plus de 50s à chaque démarrage ...

Grosse contradiction avec le résultat de systemd-analyze blame. Ça fait penser à une erreur d'UUID sur la swap.
Donne-nous, stp, le retour de sudo blkid et le contenu de ton /etc/fstab.

[pepit]

Je doute avoir fait ce genre d'erreur car j'ai usé du copié-collé mais comme une erreur est toujours possible voici les sorties demandées:

Code : Tout sélectionner

[pepit@wawa ~]$ sudo blkid
Mot de passe [sudo] de pepit :
/dev/sdb1: LABEL="MAISON" UUID="58d1a8ed-bcdb-4c9c-8c48-f1d50fa43a7f" TYPE="ext4" PARTUUID="000d6282-01"
/dev/sda1: LABEL="WINDOCS" UUID="36BFED20536BF2DA" TYPE="ntfs" PARTUUID="0000a3df-01"
/dev/sdc1: LABEL="WIN" UUID="153CDC187BD4AC49" TYPE="ntfs" PARTUUID="dccf69bb-01"
/dev/sdc2: LABEL="Boot" UUID="fb39eeab-5591-46e0-92bb-36b2ee977a97" TYPE="ext2" PARTUUID="dccf69bb-02"
/dev/sdc3: LABEL="Linux" UUID="3a95fe6d-3723-4497-b395-9f76a296077b" TYPE="ext4" PARTUUID="dccf69bb-03"
/dev/sdc4: LABEL="Swap" UUID="1dcdf1d0-436b-4a1b-9f6a-62cfecf04da6" TYPE="swap" PARTUUID="dccf69bb-04"
/dev/sdd1: LABEL="SEVEN" UUID="7ADE0C770D9074A8" TYPE="ntfs" PARTUUID="000637f6-01"
/dev/sdd2: LABEL="boot" UUID="3f4289e3-1590-4f41-926b-354e5e6aba33" TYPE="ext2" PARTUUID="000637f6-02"
/dev/sdd3: UUID="5d63bcb8-7c00-4b6f-9a52-381ec07bbc42" TYPE="swap" PARTUUID="000637f6-03"
/dev/sdd4: LABEL="Arch" UUID="f79c6a31-6cf3-417e-9ac5-4396e3fba8ef" TYPE="ext4" PARTUUID="000637f6-04"

Mon "fstab", la partition de boot utilisée est encore sur mon disque SAS pour assurer le démarage de Win 10 mais à la fin du processus tout se fera sur le ssd...

Code : Tout sélectionner

# 
# /etc/fstab: static file system information
#
# <file system>	<dir>	<type>	<options>	<dump>	<pass>

# LABEL=Linux
UUID=3a95fe6d-3723-4497-b395-9f76a296077b	/         	ext4      	rw,relatime,data=ordered		0 1

# LABEL=boot
UUID=3f4289e3-1590-4f41-926b-354e5e6aba33	/boot     	ext2      	rw,noauto,relatime			1 2

# LABEL=Swap
UUID=1dcdf1d0-436b-4a1b-9f6a-62cfecf04da6	none      	swap      	defaults				0 0

# Donnees linux
# LABEL=MAISON
UUID=58d1a8ed-bcdb-4c9c-8c48-f1d50fa43a7f	/mnt/MAISON	ext4		rw,relatime,data=ordered		0 3

# LABEL=WIN
UUID=7ADE0C770D9074A8				/mnt/WIN	ntfs		rw,user,exec,umask=2,gid=1000,uid=0	0 0

# LABEL=WINDOCS
UUID=36BFED20536BF2DA				/mnt/WINDOCS	ntfs		rw,user,exec,umask=2,gid=1000,uid=0	0 0

/dev/cdrom					/media/cdrom		auto	ro,noauto				0 0

# /dev/fd0					/media/disquette	auto	noauto					0 0

[waitnsea]

Bon, tu as 2 swaps, pourquoi...?, mais comme ton fstab n'en monte qu'une je ne pense pas que la lenteur du boot vienne de là.
Tu as réessayé après avoir quitté ton Windows par un reboot plutôt qu'un "arrêt" qui n'est qu'une hibernation ? Chaque marque organisant ses Bios à sa façon, autant couper les fausses pistes...
Tu ne vas pas couper à nous poster dmesg -T et journalctl -xb , éventuellement sur pastebin...
Il y a aussi "EATON Ellipse MAX" que tu as remarqué, c'est un onduleur ?
Essaie aussi en le shuntant ....

[waitnsea]

Plus je revois tes posts et ton systemd-analyze plus je pense que ton Arch va très bien et que tout se joue en amont entre bios et HW.

[pepit]

Merci beaucoup pour ton aide!
Le swap de l'autre disque est celui de mon ancien disque que je doit à à la fin du processus de migration reformater en disque de données raid 5.
J'ai remarqué que lorsque je fait un "reboot" le contrôleur SAS reste souvent bloqué lors de sa séquence de lancement et ce, quelque soit le système utilisé.
Le EATON Elipse MAX est un onduleur que plasma 5 ne digère pas et pas trouvé de solution à ce jour:
viewtopic.php?f=13&t=17479&p=148256#p148256
Mais je viens de voir que je n'ai pas encore essayé la dernière solution!

Sortie de dmesg -T:
https://pastelink.net/fhs

Sortie de journalctl -xb

Code : Tout sélectionner

[pepit@wawa ~]$ journalctl -xb
Hint: You are currently not seeing messages from other users and the system.
      Users in groups 'adm', 'systemd-journal', 'wheel' can see all messages.
      Pass -q to turn off this notice.
-- Logs begin at dim. 2015-10-04 12:46:33 CEST, end at dim. 2015-12-20 13:42:32 CET. --
déc. 19 14:10:42 wawa systemd[468]: Starting D-Bus User Message Bus Socket.
-- Subject: L'unité (unit) UNIT a commencé à démarrer
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
-- 
-- L'unité (unit) UNIT a commencé à démarrer.
déc. 19 14:10:42 wawa systemd[468]: Reached target Timers.
-- Subject: L'unité (unit) UNIT a terminé son démarrage
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel
-- 
-- L'unité (unit) UNIT a terminé son démarrage, avec le résultat done.
déc. 19 14:10:42 wawa systemd[468]: Reached target Paths.
-- Subject: L'unité (unit) UNIT a terminé son démarrage
-- Defined-By: systemd
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel                                                      
--                                                                                                                           
-- L'unité (unit) UNIT a terminé son démarrage, avec le résultat done.                                                       
déc. 19 14:10:42 wawa systemd[468]: Listening on Sound System.                                                               
-- Subject: L'unité (unit) UNIT a terminé son démarrage                                                                      
-- Defined-By: systemd                                                                                                       
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel                                                      
--                                                                                                                           
-- L'unité (unit) UNIT a terminé son démarrage, avec le résultat done.                                                       
déc. 19 14:10:42 wawa systemd[468]: Listening on D-Bus User Message Bus Socket.                                              
-- Subject: L'unité (unit) UNIT a terminé son démarrage                                                                      
-- Defined-By: systemd                                                                                                       
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel                                                      
--                                                                                                                           
-- L'unité (unit) UNIT a terminé son démarrage, avec le résultat done.                                                       
déc. 19 14:10:42 wawa systemd[468]: Reached target Sockets.                                                                  
-- Subject: L'unité (unit) UNIT a terminé son démarrage                                                                      
-- Defined-By: systemd                                                                                                       
-- Support: http://lists.freedesktop.org/mailman/listinfo/systemd-devel                                                      
--                                                                                                                           
-- L'unité (unit) UNIT a terminé son démarrage, avec le résultat done.                                                       
déc. 19 14:10:42 wawa systemd[468]: Reached target Basic System.
-- Subject: L'unité (unit) UNIT a terminé son démarrage
-- Defined-By: systemd

[waitnsea]

Trop court la sortie de journalctl -xb !
C'est une commande dont le retour s'allonge en appuyant sur la touche Return.
Exemple : si je fais journalctl -xb > journal.txt puis que j'édite le résultat avec un utilitaire affichant les numéros de ligne, medit par exemple, ou geany, j'obtiens un document de 2747 lignes.
Après on peut trier dans le document avec la fonction recherche ou depuis la commande avec grep.
Passe-le aussi en pastebin ?

[pepit]

Oups, je l'avais pourtant vu mais oublié de le faire, du reste sans tes conseils de redirection j'aurai eu beaucoup de mal!!
Je l'ai fait avec kwrite, j'espère que ce ne sera pas trop andicapant.
https://pastelink.net/fjm

[waitnsea]

Bon, ce n'est plus trop de ma compétence, je pense que les barbus ne vont pas tarder à passer par ici.
Je vois déjà (prenons les choses une par une) un "failed" qui me dirige vers une floppée de "wawa"
wawa ntpd, wawa-systemd ...!
je ne connais pas de paquet de ce nom, je vois juste un http://wawa-mania.biz/kernel-for-window ... eygen-brd/ dont je me demande comment il pourrait bien s'intégrer à systemd...
Attendons-voir les avis des informaticiens du club...

Code : Tout sélectionner

pkgfile wawa

ne renvoie rien ...

[pepit]

Lol, wawa est le nom que j'ai donné à ma machine! ça doit être normal qu'il figure un peu partout non?
Remplace le par localhost et ce sera peut-être plus compréensible

[waitnsea]

Il aboie mais ne mord pas...

[pepit]

Pour la petite histoire : Il y a une vintaines d'années que mon tout jeune fils avait batisé mon 486DX4 100 "wawa", parceque lorsque que Win95 démarrais il lisait un un puissant "welcome". Depuis ce jour tout mes PC on été batisé comme ça. (rien à voir avec les célèbres hackeurs apparus plus tard...)

[pepit]

Un peu d'eau au moulin:

Code : Tout sélectionner

[ven. déc. 18 17:48:57 2015] hid-generic 0003:0463:FFFF.0006: hiddev0,hidraw5: USB HID v1.10 Device [EATON Ellipse MAX] on usb-0000:00:14.0-12/input0
[ven. déc. 18 17:49:13 2015] scsi host12: ioc0: LSISAS1068E B3, FwRev=00192f00h, Ports=1, MaxQ=266, IRQ=16

J'essayerai de redémarrer en débranchant l'usb de l'onduleur pour voir, je vous tiendrai au courant de la suite!

Fait hier: même problème donc je pense que c'est plutôt la deuxième ligne qui retarde le démarrage:

Code : Tout sélectionner

[ven. déc. 18 17:49:13 2015] scsi host12: ioc0: LSISAS1068E B3, FwRev=00192f00h, Ports=1, MaxQ=266, IRQ=16

J'ai aussi constaté sur un autre PC Archlinux que je suis en train de mettre au point un triggering uevents aussi long que sur mon PC et devinez quoi?

Ben il tourne aussi en SCSI, et il s'agit d'un contrôleur ancien Adaptec intégré à une CM Asus (PIIBS). Sur mon PC actuel, le contrôleur est un Dell basé sur LSI SAS. Donc il est très probable que ça vienne du SCSI et non des contrôleurs. Peut être le noyau Linux plutôt que Arch, qu'en pensez vous?

[pepit]

Je confirme le message précédent:
Ayant finit la migration système sur mon disque SSD, j'ai fait un essais de démarrage sans le contrôleur SAS: Plus de latence!
C'est bien, mais ça ne résout pas le problème car j'ai l'intention de l'utiliser pour mes disques de données en RAID 5 (si possible).
Des idées pour accélérer ce démarrage?