Gentoo Websites Logo
Go to: Gentoo Home Documentation Forums Lists Bugs Planet Store Wiki Get Gentoo!
Bug 167451 - sys-fs/mdadm-2.6 huge logging/mailing for no reason
Summary: sys-fs/mdadm-2.6 huge logging/mailing for no reason
Status: RESOLVED FIXED
Alias: None
Product: Gentoo Linux
Classification: Unclassified
Component: Current packages (show other bugs)
Hardware: AMD64 Linux
: High major (vote)
Assignee: Gentoo's Team for Core System packages
URL:
Whiteboard:
Keywords:
Depends on:
Blocks: 166309
  Show dependency tree
 
Reported: 2007-02-18 10:43 UTC by Tomas Synek
Modified: 2007-09-04 18:20 UTC (History)
11 users (show)

See Also:
Package list:
Runtime testing required: ---


Attachments
patch to mdadm-2.6 (mdadm-2.6-spareactive.patch,401 bytes, patch)
2007-02-21 17:00 UTC, parafin
Details | Diff
patch to ebuild (mdadm-2.6.ebuild.patch,364 bytes, patch)
2007-02-21 17:02 UTC, parafin
Details | Diff

Note You need to log in before you can comment on or make changes to this bug.
Description Tomas Synek 2007-02-18 10:43:22 UTC
I tried to upgrade mdadm to new stable version 2.6 on my amd64 machine, and I didn't understand why the deamon is logging so much (every minute). Raids are ok, configs without change. Daemon also ignores my email adress admin-sunny@ok.cvut.cz in config and sends nonsense emails like to=</dev/md2@sunny.ok.cvut.cz> or to=</dev/md5@sunny.ok.cvut.cz>.
When I switch back to old mdadm-2.5.2, it is fine.


Reproducible: Always

Steps to Reproduce:
1. emerge mdadm-2.6
2. run mdadm

Actual Results:  
Feb 14 17:14:43 sunny mdadm: NewArray event detected on md device /dev/md1
Feb 14 17:14:43 sunny mdadm: NewArray event detected on md device /dev/md2
Feb 14 17:14:43 sunny mdadm: NewArray event detected on md device /dev/md3
Feb 14 17:14:43 sunny mdadm: NewArray event detected on md device /dev/md5
Feb 14 17:15:43 sunny mdadm: SpareActive event detected on md device /dev/md5
Feb 14 17:15:43 sunny mdadm: SpareActive event detected on md device /dev/md5
Feb 14 17:15:43 sunny mdadm: SpareActive event detected on md device /dev/md2
Feb 14 17:15:43 sunny mdadm: SpareActive event detected on md device /dev/md2
Feb 14 17:16:43 sunny mdadm: SpareActive event detected on md device /dev/md5
Feb 14 17:16:43 sunny mdadm: SpareActive event detected on md device /dev/md5
Feb 14 17:16:43 sunny mdadm: SpareActive event detected on md device /dev/md2
Feb 14 17:16:43 sunny mdadm: SpareActive event detected on md device /dev/md2
Feb 14 17:17:44 sunny mdadm: SpareActive event detected on md device /dev/md5
Feb 14 17:17:44 sunny mdadm: SpareActive event detected on md device /dev/md5
Feb 14 17:17:44 sunny mdadm: SpareActive event detected on md device /dev/md2
Feb 14 17:17:44 sunny mdadm: SpareActive event detected on md device /dev/md2
Feb 14 17:18:44 sunny mdadm: SpareActive event detected on md device /dev/md5
Feb 14 17:18:44 sunny mdadm: SpareActive event detected on md device /dev/md5
Feb 14 17:18:44 sunny mdadm: SpareActive event detected on md device /dev/md2
Feb 14 17:18:44 sunny mdadm: SpareActive event detected on md device /dev/md2
Feb 14 17:19:45 sunny mdadm: SpareActive event detected on md device /dev/md5
Feb 14 17:19:45 sunny mdadm: SpareActive event detected on md device /dev/md5
Feb 14 17:19:45 sunny mdadm: SpareActive event detected on md device /dev/md2
Feb 14 17:19:45 sunny mdadm: SpareActive event detected on md device /dev/md2



Expected Results:  
Do not log/mail every minute for no reason :)

sunny ~ # emerge --info
Portage 2.1.2-r9 (default-linux/amd64/2006.1/server, gcc-4.1.1, glibc-2.4-r4, 2.6.19-hardened-r5 x86_64)
=================================================================
System uname: 2.6.19-hardened-r5 x86_64 AMD Opteron(tm) Processor 146
Gentoo Base System release 1.12.6
Timestamp of tree: Sat, 17 Feb 2007 18:50:01 +0000
dev-lang/python:     2.4.3-r4
dev-python/pycrypto: 2.0.1-r5
sys-apps/sandbox:    1.2.17
sys-devel/autoconf:  2.13, 2.61
sys-devel/automake:  1.4_p6, 1.5, 1.6.3, 1.7.9-r1, 1.8.5-r3, 1.9.6-r2, 1.10
sys-devel/binutils:  2.16.1-r3
sys-devel/gcc-config: 1.3.14
sys-devel/libtool:   1.5.22
virtual/os-headers:  2.6.11-r2
ACCEPT_KEYWORDS="amd64"
AUTOCLEAN="yes"
CBUILD="x86_64-pc-linux-gnu"
CFLAGS="-march=opteron -O2 -pipe -fomit-frame-pointer"
CHOST="x86_64-pc-linux-gnu"
CONFIG_PROTECT="/etc"
CONFIG_PROTECT_MASK="/etc/env.d /etc/gconf /etc/revdep-rebuild /etc/terminfo"
CXXFLAGS="-march=opteron -O2 -pipe -fomit-frame-pointer"
DISTDIR="/usr/portage/distfiles"
FEATURES="autoconfig distlocks metadata-transfer sandbox sfperms strict"
GENTOO_MIRRORS="ftp://gentoo.inode.at/source http://gentoo.inode.at  ftp://ftp.sh.cvut.cz/MIRRORS/gentoo/gentoo ftp://files.gentoo.org"
PKGDIR="/usr/portage/packages"
PORTAGE_RSYNC_OPTS="--recursive --links --safe-links --perms --times --compress --force --whole-file --delete --delete-after --stats --timeout=180 --exclude=/distfiles --exclude=/local --exclude=/packages"
PORTAGE_TMPDIR="/var/tmp"
PORTDIR="/usr/portage"
PORTDIR_OVERLAY="/usr/local/portage"
SYNC="rsync://gentoo.inode.at/gentoo-portage"
USE="amd64 apache2 berkdb bitmap-fonts cli cracklib crypt cups dlloader dri fortran gdbm gpm hardened iconv ipv6 isdnlog libg++ mailwrapper midi mysql ncurses nls nptl nptlonly pam pcre perl postgres ppds pppd python readline reflection session snmp spl ssl tcpd truetype truetype-fonts type1-fonts unicode xml xorg zlib" ALSA_CARDS="ali5451 als4000 atiixp atiixp-modem bt87x ca0106 cmipci emu10k1x ens1370 ens1371 es1938 es1968 fm801 hda-intel intel8x0 intel8x0m maestro3 trident usb-audio via82xx via82xx-modem ymfpci" ALSA_PCM_PLUGINS="adpcm alaw asym copy dmix dshare dsnoop empty extplug file hooks iec958 ioplug ladspa lfloat linear meter mulaw multi null plug rate route share shm softvol" ELIBC="glibc" INPUT_DEVICES="keyboard mouse evdev" KERNEL="linux" LCD_DEVICES="bayrad cfontz cfontz633 glk hd44780 lb216 lcdm001 mtxorb ncurses text" USERLAND="GNU" VIDEO_CARDS="apm ark ati chips cirrus cyrix dummy fbdev glint i128 i810 mga neomagic nv rendition s3 s3virge savage siliconmotion sis sisusb tdfx tga trident tseng v4l vesa vga via vmware voodoo"
Unset:  CTARGET, EMERGE_DEFAULT_OPTS, INSTALL_MASK, LANG, LC_ALL, LDFLAGS, LINGUAS, MAKEOPTS, PORTAGE_RSYNC_EXTRA_OPTS

sunny ~ # cat /etc/fstab
# /etc/fstab: static file system information.
# <fs>                  <mountpoint>    <type>          <opts>          <dump/pass>

/dev/md1                /boot           ext2            noauto,noatime  1 2
/dev/md2                /               ext3            defaults        0 1
/dev/md3                /home           ext3            usrquota,grpquota       0 2
/dev/md5                /var            ext3            defaults        0 2
/dev/md6                none            swap            sw              0 0

/dev/cdroms/cdrom0      /mnt/cdrom      iso9660         noauto,ro       0 0

proc                    /proc           proc            defaults        0 0
shm                     /dev/shm        tmpfs           nodev,nosuid,noexec     0 0
none                    /proc/bus/usb   usbfs           defaults        0 0
Comment 1 Tomas Synek 2007-02-18 10:55:12 UTC
sunny ~ # cat /etc/init.d/mdadm 

start() { 
        ebegin "Starting mdadm monitor" 
        mdadm --monitor --scan \ 
                --daemonise \ 
                --pid-file /var/run/mdadm.pid \ 
                ${MDADM_OPTS} 
        eend $? 
} 


sunny ~ # cat /etc/conf.d/mdadm 

MDADM_OPTS="--syslog" 


sunny ~ # cat /etc/mdadm.conf 

DEVICE /dev/sd[ab][12356] 
MAILADDR admin-sunny@ok.cvut.cz
PROGRAM /usr/sbin/sendmail 


sunny ~ # cat /proc/mdstat 
Personalities : [raid0] [raid1] 
md1 : active raid1 sdb1[1] sda1[0] 
      128384 blocks [2/2] [UU] 

md2 : active raid1 sdb2[1] sda2[0] 
      10000384 blocks [2/2] [UU] 

md3 : active raid1 sdb3[1] sda3[0] 
      54998400 blocks [2/2] [UU] 

md5 : active raid1 sdb5[1] sda5[0] 
      11944192 blocks [2/2] [UU] 

md6 : active raid0 sdb6[1] sda6[0] 
      2152448 blocks 64k chunks 

unused devices: <none> 
Comment 2 Neil Skrypuch 2007-02-19 06:24:17 UTC
I'm seeing this too, every 60 seconds:

Feb 19 01:15:22 x2-42 mdadm: SpareActive event detected on md device /dev/md2
Feb 19 01:15:22 x2-42 mdadm: SpareActive event detected on md device /dev/md2
Feb 19 01:15:22 x2-42 mdadm: SpareActive event detected on md device /dev/md2
Feb 19 01:15:22 x2-42 mdadm: SpareActive event detected on md device /dev/md2
Feb 19 01:16:22 x2-42 mdadm: SpareActive event detected on md device /dev/md1
Feb 19 01:16:22 x2-42 mdadm: SpareActive event detected on md device /dev/md1
Feb 19 01:16:22 x2-42 mdadm: SpareActive event detected on md device /dev/md1
Feb 19 01:16:22 x2-42 mdadm: SpareActive event detected on md device /dev/md1
Feb 19 01:16:22 x2-42 mdadm: SpareActive event detected on md device /dev/md2
Feb 19 01:16:22 x2-42 mdadm: SpareActive event detected on md device /dev/md2
Feb 19 01:16:22 x2-42 mdadm: SpareActive event detected on md device /dev/md2
Feb 19 01:16:22 x2-42 mdadm: SpareActive event detected on md device /dev/md2
Feb 19 01:16:22 x2-42 mdadm: SpareActive event detected on md device /dev/md5
Feb 19 01:16:22 x2-42 mdadm: SpareActive event detected on md device /dev/md5
Feb 19 01:16:22 x2-42 mdadm: SpareActive event detected on md device /dev/md5
Feb 19 01:16:22 x2-42 mdadm: SpareActive event detected on md device /dev/md5
Feb 19 01:17:22 x2-42 mdadm: SpareActive event detected on md device /dev/md1
Feb 19 01:17:22 x2-42 mdadm: SpareActive event detected on md device /dev/md1
Feb 19 01:17:22 x2-42 mdadm: SpareActive event detected on md device /dev/md1
Feb 19 01:17:22 x2-42 mdadm: SpareActive event detected on md device /dev/md1
Feb 19 01:17:22 x2-42 mdadm: SpareActive event detected on md device /dev/md2
Feb 19 01:17:22 x2-42 mdadm: SpareActive event detected on md device /dev/md2
Feb 19 01:17:22 x2-42 mdadm: SpareActive event detected on md device /dev/md2
Feb 19 01:17:22 x2-42 mdadm: SpareActive event detected on md device /dev/md2

and so on...

I'm also using AMD64, though with gentoo-sources-2.6.20.
Comment 3 Carsten Böcker 2007-02-19 12:47:43 UTC
not only amd64:

uname -a:
Linux cb1 2.6.19-gentoo-r5 #2 SMP PREEMPT Wed Jan 31 12:13:25 CET 2007 i686 AMD Athlon(tm) XP 2200+ AuthenticAMD GNU/Linux

part of syslog:
Feb 19 13:40:33 localhost mdadm: SpareActive event detected on md device /dev/md0
Feb 19 13:41:33 localhost last message repeated 2 times
Feb 19 13:43:33 localhost last message repeated 4 times
Comment 4 Toby Murray 2007-02-19 15:20:40 UTC
Seeing the same thing on two i686 boxes. However I am not seeing any problems with email notification.
Comment 5 Matthew Schultz 2007-02-21 06:13:19 UTC
I'm getting the same problem.  What the hell is with all this logging on arrays that are working properly?  Is this an upstream bug?  Is this by design?  If so, can we have an option to set the log level?  This looks like it's an INFO level log and most likely nothing to be concerned about.  I was thinking I could change the level to log at a minimum of WARN but I don't see the option in the docs.  Also the log comes from monitoring and depending on what you set for the delay, you will see this message every time the monitor utility does a check.  Stopping the monitor daemon stops these messages.

Feb 21 00:01:20 server mdadm: SpareActive event detected on md device /dev/md2
Feb 21 00:01:20 server mdadm: SpareActive event detected on md device /dev/md2
Feb 21 00:02:20 server mdadm: SpareActive event detected on md device /dev/md2
Feb 21 00:02:20 server mdadm: SpareActive event detected on md device /dev/md2
Feb 21 00:03:20 server mdadm: SpareActive event detected on md device /dev/md2
Feb 21 00:03:20 server mdadm: SpareActive event detected on md device /dev/md2
Feb 21 00:04:20 server mdadm: SpareActive event detected on md device /dev/md2
Feb 21 00:04:20 server mdadm: SpareActive event detected on md device /dev/md2
Feb 21 00:04:20 server mdadm: SpareActive event detected on md device /dev/md3
Feb 21 00:04:20 server mdadm: SpareActive event detected on md device /dev/md3
Feb 21 00:05:20 server mdadm: SpareActive event detected on md device /dev/md2
Feb 21 00:05:20 server mdadm: SpareActive event detected on md device /dev/md2
Feb 21 00:06:20 server mdadm: SpareActive event detected on md device /dev/md2
Feb 21 00:06:20 server mdadm: SpareActive event detected on md device /dev/md2
Comment 6 SpanKY gentoo-dev 2007-02-21 06:23:35 UTC
try removing the syslog-updates.patch from the ebuild
Comment 7 parafin 2007-02-21 16:17:28 UTC
No, this doesn't help. Downgrading helps...
Comment 8 parafin 2007-02-21 17:00:15 UTC
Created attachment 110848 [details, diff]
patch to mdadm-2.6

This patch helps.
Comment 9 parafin 2007-02-21 17:02:20 UTC
Created attachment 110850 [details, diff]
patch to ebuild
Comment 10 Ernst Herzberg 2007-02-22 15:56:53 UTC
Should be fixed in 2.6.1

see http://www.kernel.org/pub/linux/utils/raid/mdadm/ChangeLog

(yes, this bug is very annoying , pls bump...)
Comment 11 Wolfram Schlich (RETIRED) gentoo-dev 2007-02-27 10:32:46 UTC
Fixed in CVS.
Comment 12 Jonathan Thibault 2007-09-04 15:28:16 UTC
I've been having this very same problem for a long while.  I'm running mdadm 2.6.2 now and I checked Monitor.c to see if the patch is included.  Apparently it is, but I still get the SpareActive every minute or so in syslog.

This is a pair of disks with three RAID1 partitions.  The only MD device giving me trouble is my root partition (reiserfs).  Swap and boot are not giving me any SpareActive events.
Comment 13 Jonathan Thibault 2007-09-04 18:20:55 UTC
Okay, 2.6.3 fixed it for me.