On 2017-01-13 20:43, Wols Lists wrote:
On 13/01/17 19:24, Carlos E. R. wrote:
<3.6> 2016-12-24 00:55:00 Telcontar systemd 1 - - Starting LSB: mdadmd daemon monitoring MD devices... <3.6> 2016-12-24 00:55:00 Telcontar echo 3716 - - Starting virus-scanner (amavisd-new): <3.6> 2016-12-24 00:55:01 Telcontar mdadmd 3717 - - Starting mdadmd ..done
mdadm is called 20 seconds after the array appears on boot. According to the log above, it is the kernel directly who does the assembly.
What kernel? What distro?
The first line in the log printed the kernel version. But I made a mistake, that log is for 13.1.
Oh - and that first line I've quoted says "starting mdadmd monitoring" ... I don't think that's anything to do with starting the arrays, it's firing up the monitor that emails you if anything goes wrong ...
Yes. That's the first appearance of mdadm in the log. The array was mounted earlier. On Leap 42.2: 2016-12-26 20:39:16+01:00 - Booting the system now ================================================================================ Linux Telcontar 4.4.36-8-default #1 SMP Fri Dec 9 16:18:38 UTC 2016 (3ec5648) x86_64 x86_64 x86_64 GNU/Linux <5.6> 2016-12-26 20:39:22 Telcontar rsyslogd - - - [origin software="rsyslogd" swVersion="8.4.0" x-pid="1551" x-info="http://www.rsyslog.com"] start <0.6> 2016-12-26 20:39:22 Telcontar kernel - - - [ 0.000000] microcode: CPU0 microcode updated early to revision 0xa0b, date = 2010-09-28 <3.6> 2016-12-26 20:39:22 Telcontar systemd 1 - - Found device /dev/disk/by-label/raid5. <3.5> 2016-12-26 20:39:22 Telcontar systemd 1 - - data-raid.mount: Directory /data/raid to mount over is not empty, mounting anyway. <3.6> 2016-12-26 20:39:22 Telcontar systemd 1 - - Mounting /data/raid... 0.5> 2016-12-26 20:39:22 Telcontar kernel - - - [ 24.758408] REISERFS (device sdc6): Using r5 hash to sort names <0.6> 2016-12-26 20:39:22 Telcontar kernel - - - [ 24.876010] raid6: sse2x1 gen() 4952 MB/s <0.6> 2016-12-26 20:39:22 Telcontar kernel - - - [ 24.944002] raid6: sse2x1 xor() 4806 MB/s <0.6> 2016-12-26 20:39:22 Telcontar kernel - - - [ 25.012010] raid6: sse2x2 gen() 5267 MB/s <0.6> 2016-12-26 20:39:22 Telcontar kernel - - - [ 25.080008] raid6: sse2x2 xor() 5807 MB/s <0.6> 2016-12-26 20:39:22 Telcontar kernel - - - [ 25.148004] raid6: sse2x4 gen() 8218 MB/s <0.6> 2016-12-26 20:39:22 Telcontar kernel - - - [ 25.188327] XFS (sdc8): Ending clean mount <0.6> 2016-12-26 20:39:22 Telcontar kernel - - - [ 25.216008] raid6: sse2x4 xor() 6689 MB/s <0.6> 2016-12-26 20:39:22 Telcontar kernel - - - [ 25.216009] raid6: using algorithm sse2x4 gen() 8218 MB/s <0.6> 2016-12-26 20:39:22 Telcontar kernel - - - [ 25.216011] raid6: .... xor() 6689 MB/s, rmw enabled <0.6> 2016-12-26 20:39:22 Telcontar kernel - - - [ 25.216012] raid6: using ssse3x2 recovery algorithm <0.6> 2016-12-26 20:39:22 Telcontar kernel - - - [ 25.905538] md: raid6 personality registered for level 6 <0.6> 2016-12-26 20:39:22 Telcontar kernel - - - [ 25.905540] md: raid5 personality registered for level 5 <0.6> 2016-12-26 20:39:22 Telcontar kernel - - - [ 25.905540] md: raid4 personality registered for level 4 <0.5> 2016-12-26 20:39:22 Telcontar kernel - - - [ 25.932459] audit: type=1400 audit(1482781131.317:37): apparmor="STATUS" operation="profile_load" name="/usr/lib/nagios/plugins/check_icmp" pid=1 169 comm="apparmor_parser" <0.6> 2016-12-26 20:39:22 Telcontar kernel - - - [ 25.936062] md/raid:md0: device sda11 operational as raid disk 0 <0.6> 2016-12-26 20:39:22 Telcontar kernel - - - [ 25.936063] md/raid:md0: device sdc15 operational as raid disk 2 <0.6> 2016-12-26 20:39:22 Telcontar kernel - - - [ 25.936064] md/raid:md0: device sdb10 operational as raid disk 1 <0.6> 2016-12-26 20:39:22 Telcontar kernel - - - [ 25.951834] md/raid:md0: allocated 3316kB <0.6> 2016-12-26 20:39:22 Telcontar kernel - - - [ 25.953008] md/raid:md0: raid level 5 active with 3 out of 3 devices, algorithm 2 <0.7> 2016-12-26 20:39:22 Telcontar kernel - - - [ 25.953008] RAID conf printout: <0.7> 2016-12-26 20:39:22 Telcontar kernel - - - [ 25.953009] --- level:5 rd:3 wd:3 <0.7> 2016-12-26 20:39:22 Telcontar kernel - - - [ 25.953010] disk 0, o:1, dev:sda11 <0.7> 2016-12-26 20:39:22 Telcontar kernel - - - [ 25.953011] disk 1, o:1, dev:sdb10 <0.7> 2016-12-26 20:39:22 Telcontar kernel - - - [ 25.953012] disk 2, o:1, dev:sdc15 <0.5> 2016-12-26 20:39:22 Telcontar kernel - - - [ 25.984767] audit: type=1400 audit(1482781131.369:38): apparmor="STATUS" operation="profile_load" name="/usr/lib/nagios/plugins/check_ide_smart" pid=1187 comm="apparmor_parser" <0.6> 2016-12-26 20:39:22 Telcontar kernel - - - [ 26.037347] created bitmap (1 pages) for device md0 <0.5> 2016-12-26 20:39:22 Telcontar kernel - - - [ 26.057130] audit: type=1400 audit(1482781131.441:39): apparmor="STATUS" operation="profile_load" name="/usr/lib/nagios/plugins/check_load" pid=1193 comm="apparmor_parser" <0.6> 2016-12-26 20:39:22 Telcontar kernel - - - [ 26.062355] md0: bitmap initialized from disk: read 1 pages, set 0 of 193 bits <0.6> 2016-12-26 20:39:22 Telcontar kernel - - - [ 26.262738] md0: detected capacity change from 0 to 25777668096 Now, I don't think the timing on syslog is correct, though. Everything has the same timestamp, that can't be, either. If we believe the above as exact, mounting happens earlier than assembly. It is still the kernel who does the assembly, seconds on the boot. dmesg has slightly different info: [ 24.403400] md/raid:md0: device sdb11 operational as raid disk 0 [ 24.403401] md/raid:md0: device sdd15 operational as raid disk 2 [ 24.403403] md/raid:md0: device sdc10 operational as raid disk 1 [ 24.403683] md/raid:md0: allocated 3316kB [ 24.403729] md/raid:md0: raid level 5 active with 3 out of 3 devices, algorithm 2 So it happens 24 seconds after boot. And mount: [ 27.881141] XFS (md0): Mounting V4 Filesystem [ 35.903167] XFS (md0): Starting recovery (logdev: internal) [ 37.710756] XFS (md0): Ending recovery (logdev: internal) [ 38.404876] XFS (dm-0): Mounting V4 Filesystem [ 38.600968] XFS (dm-0): Starting recovery (logdev: internal) [ 39.208397] XFS (dm-0): Ending recovery (logdev: internal) -- Cheers / Saludos, Carlos E. R. (from 42.2 x86_64 "Malachite" at Telcontar)